-- Logs begin at Tue 2025-09-02 04:16:02 CEST, end at Tue 2025-09-02 04:43:44 CEST. -- Sep 02 04:42:00 volumio volumio[10090]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:00 volumio volumio[10090]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:00 volumio volumio[10090]: info: Reading ALSA contributions from plugins. Sep 02 04:42:00 volumio volumio[10090]: info: MPD Permissions set Sep 02 04:42:00 volumio volumio[10090]: info: MPD Permissions set Sep 02 04:42:00 volumio volumio[10090]: info: Spotify config file written Sep 02 04:42:00 volumio sudo[10255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:42:00 volumio sudo[10255]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:00 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:42:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:00 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:42:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:42:00 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:42:00 volumio go-librespot[10258]: go-librespot daemon starting... Sep 02 04:42:00 volumio volumio[10090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="app state loaded" Sep 02 04:42:00 volumio volumio[10090]: info: Volumio called home Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:42:00 volumio sudo[10255]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio sudo[10266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:00 volumio sudo[10266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio sudo[10266]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:00 volumio sudo[10268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:00 volumio sudo[10268]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:00 volumio sudo[10268]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:00 volumio volumio[10090]: info: No need to fix Spotify hosts Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=info msg="zeroconf server listening on port 35591" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="obtained new client token: AABcaqGpniW6GguIpEbupvWTJT3iXxj5YNmbwfAakddy3D8oOFa9/BBRDtbi4K7qkxgMMJVtoHRbPBCmZfGcR91tSDKx0FcqBSoifCl83uqhJ0OdUfCveGFlQkBzry9dzGR06RqxAU300q1OmBdSvCd5PcGRp71c+hwWRnDmkKuPv2psk8BiJaKkg1jZD9UHEOWag37VhOG2+rRJvRsprHT5m5bNqedhPZ7s/ovvj3cYzB8RoKAAY/DGVqw=" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:42:00 volumio volumio[10090]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:00 volumio volumio[10090]: SPOTIFY: BQD9q1aOXIOUy2pHNv9ADhDRAcjeuxjYB6D0abiqn9o_vtHEVTxTCHROROf3TzV71rpi65WVbRiVHBo9jngm3peLVNJbxzvqJyI8M6Ao085R1cap8X6J21loXpIWzzeJPmQ7MfIM5QtxDQ2HZuSCjcC6mr7nNvlVEgMW1txmE37mC_XCEgWuPNh9ZG8hzz2O0J5rzZgb-38t-rZxm2FloUwLRPl_-wFXKFteorNdAVX_-LUKryAXJVXibNqg06AbVvRTjDjnCJzN6zGHJ3z9rtfu5GQL3XT5gfvwrpo2HjtFVU4 Sep 02 04:42:00 volumio volumio[10090]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:00 volumio volumio[10090]: info: New Spotify access token = BQD9q1aOXIOUy2pHNv9ADhDRAcjeuxjYB6D0abiqn9o_vtHEVTxTCHROROf3TzV71rpi65WVbRiVHBo9jngm3peLVNJbxzvqJyI8M6Ao085R1cap8X6J21loXpIWzzeJPmQ7MfIM5QtxDQ2HZuSCjcC6mr7nNvlVEgMW1txmE37mC_XCEgWuPNh9ZG8hzz2O0J5rzZgb-38t-rZxm2FloUwLRPl_-wFXKFteorNdAVX_-LUKryAXJVXibNqg06AbVvRTjDjnCJzN6zGHJ3z9rtfu5GQL3XT5gfvwrpo2HjtFVU4 Sep 02 04:42:00 volumio volumio[10090]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="completed keyexchange" Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=debug msg="completed challenge" Sep 02 04:42:00 volumio volumio[10090]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:00 volumio volumio[10090]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:00 volumio go-librespot[10258]: time="2025-09-02T04:42:00+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:42:01 volumio volumio[10090]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:01 volumio volumio[10090]: info: Output device has changed, restarting MPD Sep 02 04:42:01 volumio sudo[10281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:01 volumio sudo[10281]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:01 volumio sudo[10281]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:01 volumio sudo[10284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:01 volumio sudo[10284]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:42:01 volumio volumio[10090]: info: MPD Permissions set Sep 02 04:42:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:01 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:01 volumio sudo[10288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:42:01 volumio sudo[10288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:01 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="dealer connection opened" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=trace msg="received accesspoint ping" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="received connection id: Y2IzZGFiYzMtNDky...NUU0QUIzQTY5Ng==" Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:01 volumio sudo[10288]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:01 volumio volumio[10090]: info: Upmpdcli Daemon Started Sep 02 04:42:01 volumio volumio[10090]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:42:01 volumio volumio[10090]: info: Spotify Successfully logged in Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:01 volumio volumio[10090]: info: [1756780921295] CoreMusicLibrary::Adding element Spotify Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:01 volumio volumio[10090]: Cannot find translation for source Jellyfin Sep 02 04:42:01 volumio volumio[10090]: Cannot find translation for source Spotify Sep 02 04:42:01 volumio go-librespot[10258]: time="2025-09-02T04:42:01+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:42:01 volumio sudo[10287]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:01 volumio sudo[10287]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:01 volumio sudo[10287]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:01 volumio volumio[10090]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:42:01 volumio volumio[10090]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:01 volumio volumio[10090]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:01 volumio volumio[10090]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:42:01 volumio volumio[10090]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:02 volumio volumio[10090]: info: camilladsp service started and running in background, instance 1 Sep 02 04:42:02 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:02 volumio volumio[10090]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:02 volumio volumio[10090]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:42:02 volumio volumio[10090]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:02 volumio volumio[10090]: info: FusionDsp loaded Sep 02 04:42:02 volumio volumio[10090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:02 volumio sudo[10309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:42:02 volumio sudo[10309]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:02 volumio sudo[10309]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:02 volumio volumio[10090]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:42:02 volumio volumio[10090]: info: Adding Signal Path Element [object Object] Sep 02 04:42:02 volumio volumio[10090]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:42:02 volumio volumio[10090]: info: FusionDsp - ---- installed callbackRead Sep 02 04:42:02 volumio volumio[10090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:02 volumio volumio[10090]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:42:02 volumio volumio[10090]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:42:02 volumio volumio[10090]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:42:02 volumio volumio[10090]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:42:02 volumio volumio[10090]: errno: -2, Sep 02 04:42:02 volumio volumio[10090]: code: 'ENOENT', Sep 02 04:42:02 volumio volumio[10090]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:02 volumio volumio[10090]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:02 volumio volumio[10090]: spawnargs: [ Sep 02 04:42:02 volumio volumio[10090]: '-p', Sep 02 04:42:02 volumio volumio[10090]: 9876, Sep 02 04:42:02 volumio volumio[10090]: '-o', Sep 02 04:42:02 volumio volumio[10090]: '/tmp/camilladsp.log', Sep 02 04:42:02 volumio volumio[10090]: '-l', Sep 02 04:42:02 volumio volumio[10090]: 'warn', Sep 02 04:42:02 volumio volumio[10090]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:42:02 volumio volumio[10090]: ] Sep 02 04:42:02 volumio volumio[10090]: } Sep 02 04:42:02 volumio volumio[10090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:02 volumio sudo[10320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:41 Sep 02 04:42:02 volumio sudo[10320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:02 volumio sudo[10320]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:02 volumio mpd[10296]: Sep 02 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:42:02 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:42:02 volumio sudo[10284]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:02 volumio sudo[10206]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:02 volumio sudo[10220]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:42:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:42:03 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:42:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1481. Sep 02 04:42:03 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:03 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:42:03 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:42:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:03 volumio volumio-remote-updater[504]: [2025-09-02 04:42:03] [info] asio async_connect error: system:111 (Connection refused) Sep 02 04:42:03 volumio volumio-remote-updater[504]: [2025-09-02 04:42:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 04:42:03 volumio volumio-remote-updater[504]: [2025-09-02 04:42:03] [error] handle_connect error: Underlying Transport Error Sep 02 04:42:04 volumio volumio[10333]: info: ------------------------------------------- Sep 02 04:42:04 volumio volumio[10333]: info: ----- Volumio3 ---- Sep 02 04:42:04 volumio volumio[10333]: info: ------------------------------------------- Sep 02 04:42:04 volumio volumio[10333]: info: ----- System startup ---- Sep 02 04:42:04 volumio volumio[10333]: info: ------------------------------------------- Sep 02 04:42:05 volumio volumio[10333]: info: MYVOLUMIO Environment detected Sep 02 04:42:05 volumio volumio[10333]: info: Plugin folders cleanup Sep 02 04:42:05 volumio volumio[10333]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category audio_interface Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category miscellanea Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category music_service Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category plugins.json Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category system_controller Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category user_interface Sep 02 04:42:05 volumio volumio[10333]: info: Scanning into folder /data/plugins/ Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category audio_interface Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category music_service Sep 02 04:42:05 volumio volumio[10333]: info: Scanning category user_interface Sep 02 04:42:05 volumio volumio[10333]: info: Plugin folders cleanup completed Sep 02 04:42:05 volumio volumio[10333]: info: ------------------------------------------- Sep 02 04:42:05 volumio volumio[10333]: info: ----- Core plugins startup ---- Sep 02 04:42:05 volumio volumio[10333]: info: ------------------------------------------- Sep 02 04:42:05 volumio volumio[10333]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:42:05 volumio volumio[10333]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:42:05 volumio volumio[10333]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:42:05 volumio volumio[10333]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:42:05 volumio volumio[10333]: info: Loading plugins from folder /data/plugins/ Sep 02 04:42:05 volumio volumio[10333]: info: Loading plugin "system"... Sep 02 04:42:05 volumio volumio[10333]: info: Loading plugin "appearance"... Sep 02 04:42:06 volumio volumio[10333]: info: Loading plugin "network"... Sep 02 04:42:06 volumio volumio[10333]: info: Refreshing Cached IP Addresses Sep 02 04:42:06 volumio sudo[10359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:06 volumio sudo[10359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:06 volumio sudo[10359]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:06 volumio sudo[10361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:06 volumio volumio[10333]: info: Loading plugin "services"... Sep 02 04:42:06 volumio sudo[10361]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:06 volumio volumio[10333]: info: Loading plugin "alsa_controller"... Sep 02 04:42:06 volumio sudo[10361]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:06 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:06 volumio volumio[10333]: info: Loading plugin "wizard"... Sep 02 04:42:06 volumio volumio[10333]: info: Loading plugin "networkfs"... Sep 02 04:42:06 volumio volumio[10333]: info: Starting Udev Watcher for removable devices Sep 02 04:42:07 volumio volumio[10333]: info: Ignoring mount for partition: boot Sep 02 04:42:07 volumio volumio[10333]: info: Ignoring mount for partition: volumio Sep 02 04:42:07 volumio volumio[10333]: info: Ignoring mount for partition: volumio_data Sep 02 04:42:07 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "upnp"... Sep 02 04:42:07 volumio volumio[10333]: info: [1756780927031] Starting Upmpd Daemon Sep 02 04:42:07 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "my_music"... Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "mpd"... Sep 02 04:42:07 volumio volumio[10333]: info: Plugin upnp_browser is not enabled Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "alarm-clock"... Sep 02 04:42:07 volumio volumio[10333]: info: Plugin airplay_emulation is not enabled Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "last_100"... Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "webradio"... Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "i2s_dacs"... Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "volumiodiscovery"... Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** For more information see Sep 02 04:42:07 volumio node[10333]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:07 volumio volumio[10333]: *** WARNING *** For more information see Sep 02 04:42:07 volumio node[10333]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:07 volumio node[10333]: *** WARNING *** For more information see Sep 02 04:42:07 volumio node[10333]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:07 volumio node[10333]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:07 volumio node[10333]: *** WARNING *** For more information see Sep 02 04:42:07 volumio volumio[10333]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:42:07 volumio volumio[10333]: info: Discovery: Started advertising with name: Volumio Sep 02 04:42:07 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:07 volumio volumio[10333]: info: Loading plugin "jellyfin"... Sep 02 04:42:08 volumio volumio[10333]: info: Loading plugin "spop"... Sep 02 04:42:09 volumio volumio[10333]: info: Loading plugin "ytcr"... Sep 02 04:42:13 volumio volumio-remote-updater[504]: [2025-09-02 04:42:13] [connect] Successful connection Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "outputs"... Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "albumart"... Sep 02 04:42:14 volumio volumio[10333]: info: Plugin example_plugin is not enabled Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "inputs"... Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "updater_comm"... Sep 02 04:42:14 volumio volumio[10333]: info: Plugin mpdemulation is not enabled Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "rest_api"... Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "websocket"... Sep 02 04:42:14 volumio volumio[10333]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "fusiondsp"... Sep 02 04:42:14 volumio volumio[10333]: Forking 3 albumart workers Sep 02 04:42:14 volumio nmbd[721]: [2025/09/02 04:42:14.550121, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 04:42:14 volumio volumio[10333]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:42:14 volumio nmbd[721]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 04:42:14 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 04:42:14 volumio nmbd[721]: [2025/09/02 04:42:14.553317, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 04:42:14 volumio nmbd[721]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 04:42:14 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 04:42:14 volumio nmbd[721]: [2025/09/02 04:42:14.553826, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 04:42:14 volumio nmbd[721]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 04:42:14 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 04:42:14 volumio volumio[10333]: info: Loading plugin "Systeminfo"... Sep 02 04:42:15 volumio volumio[10333]: info: Loading i18n strings for locale hu Sep 02 04:42:15 volumio volumio[10333]: Updating browse sources language Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::initPlayerControls Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: Express server listening on port 3000 Sep 02 04:42:15 volumio volumio[10333]: [Metrics] WebUI: 11s 3.08ms Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::resetVolumioState Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::getcurrentVolume Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:15 volumio volumio[10333]: Starting albumart workers Sep 02 04:42:15 volumio volumio[10333]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:42:15 volumio volumio[10333]: Starting albumart workers Sep 02 04:42:15 volumio volumio[10333]: Starting albumart workers Sep 02 04:42:15 volumio volumio-remote-updater[504]: [2025-09-02 04:42:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756780933 101 Sep 02 04:42:15 volumio volumio[10333]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:15 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:15 volumio volumio[10333]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::pushState Sep 02 04:42:15 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::updateTrackBlock Sep 02 04:42:15 volumio volumio[10333]: info: CorePlayQueue::getTrackBlock Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::pushState Sep 02 04:42:15 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::setRandom true Sep 02 04:42:15 volumio volumio[10333]: info: CoreStateMachine::pushState Sep 02 04:42:15 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:15 volumio volumio[10333]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:15 volumio volumio[10333]: info: Setting Device type: Raspberry PI Sep 02 04:42:15 volumio volumio[10333]: info: Listing playlists Sep 02 04:42:15 volumio volumio[10333]: info: Listing playlists Sep 02 04:42:16 volumio volumio[10333]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:42:16 volumio volumio[10333]: info: Discovery: Found device Volumio Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:16 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:16 volumio volumio[10333]: info: Completed loading Core Plugins Sep 02 04:42:16 volumio volumio[10333]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:16 volumio volumio[10333]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:16 volumio volumio[10333]: info: Reading ALSA contributions from plugins. Sep 02 04:42:16 volumio volumio[10333]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:16 volumio volumio[10333]: info: CoreStateMachine::pushState Sep 02 04:42:16 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:16 volumio volumio[10333]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:16 volumio volumio[10333]: info: Output device has changed, restarting MPD Sep 02 04:42:16 volumio volumio[10333]: info: ___________ START PLUGINS ___________ Sep 02 04:42:16 volumio volumio[10333]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:42:16 volumio volumio[10333]: info: Creating MPD Configuration file Sep 02 04:42:16 volumio sudo[10431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:16 volumio sudo[10431]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:16 volumio sudo[10433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:16 volumio sudo[10433]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:16 volumio sudo[10431]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:42:16 volumio sudo[10445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:16 volumio sudo[10445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:16 volumio sudo[10445]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:16 volumio volumio[10333]: info: [1756780936284] CoreMusicLibrary::Adding element Last_100 Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:16 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:16 volumio volumio[10333]: info: [1756780936294] CoreMusicLibrary::Adding element Webradio Sep 02 04:42:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:16 volumio volumio[10333]: info: Initializing BBC Radios Sep 02 04:42:16 volumio sudo[10461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:16 volumio sudo[10461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:16 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:42:16 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:16 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:42:16 volumio volumio[10333]: info: Discovery: Getting this device information Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:16 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:16 volumio volumio[10333]: info: [1756780936469] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:16 volumio volumio[10333]: Cannot find translation for source Jellyfin Sep 02 04:42:16 volumio volumio[10333]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:42:16 volumio volumio[10333]: info: Creating Spotify config file Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:16 volumio sudo[10470]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:16 volumio sudo[10470]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:16 volumio sudo[10470]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:16 volumio volumio[10333]: info: Loading i18n strings for locale hu Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:42:16 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:16 volumio volumio[10333]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:42:16 volumio volumio[10333]: info: Volumio Calling Home Sep 02 04:42:16 volumio volumio[10333]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:16 volumio volumio[10333]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:16 volumio volumio[10333]: info: Reading ALSA contributions from plugins. Sep 02 04:42:16 volumio volumio[10333]: info: MPD Permissions set Sep 02 04:42:16 volumio volumio[10333]: info: MPD Permissions set Sep 02 04:42:16 volumio volumio[10333]: info: Spotify config file written Sep 02 04:42:17 volumio sudo[10497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:42:17 volumio sudo[10497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio volumio[10333]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 02 04:42:17 volumio volumio[10333]: info: Volumio called home Sep 02 04:42:17 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:42:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:42:17 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:42:17 volumio sudo[10501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:17 volumio sudo[10501]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:42:17 volumio sudo[10501]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:17 volumio go-librespot[10503]: go-librespot daemon starting... Sep 02 04:42:17 volumio sudo[10497]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="app state loaded" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:42:17 volumio sudo[10509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:17 volumio sudo[10509]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio sudo[10509]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: No need to fix Spotify hosts Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=info msg="zeroconf server listening on port 34579" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="obtained new client token: AAAJ15DbgGVDcGxXBoOg+ePbRdiCziqdhJUoxES1c2mGvuf+4aP0bMwY3jSMdG0uqoNjKmwNtyI7rYcYyULtQkyHqlHSzLYysDzo1EbiSfCITxbU+spw+jcMiTzoXKTaKZcdGDSHlhD7fkaYZszuvSwFJtdK9Hwpq4yTst9XdxYcBXOxISMH5ZaCqsM7X/RhWwtN+EXxStwBnY+YAMjLrKFyZKHUhfbKb94HyKkc7qrIDDOW1PHT5USYpJU=" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="completed keyexchange" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="completed challenge" Sep 02 04:42:17 volumio volumio[10333]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:17 volumio volumio[10333]: SPOTIFY: BQBBjqVdXWImJfnmH4nyddWwV-dlhPSNB-yDAZOQGa6ATi746MXdDdaUu64FnDjkUGF86f_BjHwy0kKqWLHf7qg8H2qHz7wmDrGPgWwDRa1ycOXk4vlJXs83KKsYeakC29AVmVd67UNTuA7AnMVFCpHorYoKghzsdwFpvbPrZoxd5TAb0zkwz1MWk9DE4Rfl5mu_npQhc6dUcXa9IgyjAfmmyDm3G6py9njXSFTTnJuDbk84aGLTTciK1BNINeVeAjEkhj1jWqRI8tyN3NuwOiZxeogaZyk-dWyL-dhp3MDO7Yk Sep 02 04:42:17 volumio volumio[10333]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:42:17 volumio volumio[10333]: info: New Spotify access token = BQBBjqVdXWImJfnmH4nyddWwV-dlhPSNB-yDAZOQGa6ATi746MXdDdaUu64FnDjkUGF86f_BjHwy0kKqWLHf7qg8H2qHz7wmDrGPgWwDRa1ycOXk4vlJXs83KKsYeakC29AVmVd67UNTuA7AnMVFCpHorYoKghzsdwFpvbPrZoxd5TAb0zkwz1MWk9DE4Rfl5mu_npQhc6dUcXa9IgyjAfmmyDm3G6py9njXSFTTnJuDbk84aGLTTciK1BNINeVeAjEkhj1jWqRI8tyN3NuwOiZxeogaZyk-dWyL-dhp3MDO7Yk Sep 02 04:42:17 volumio volumio[10333]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:17 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:17 volumio volumio[10333]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:17 volumio volumio[10333]: info: Output device has changed, restarting MPD Sep 02 04:42:17 volumio sudo[10525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:17 volumio sudo[10525]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio sudo[10525]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:17 volumio sudo[10528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:17 volumio sudo[10528]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:42:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:17 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:17 volumio volumio[10333]: info: MPD Permissions set Sep 02 04:42:17 volumio sudo[10531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:42:17 volumio sudo[10531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="dealer connection opened" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=trace msg="received accesspoint ping" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="received connection id: MmZmMDUyYTMtODQ4...OEM0MjhGMzBERg==" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:42:17 volumio go-librespot[10503]: time="2025-09-02T04:42:17+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:17 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:17 volumio sudo[10531]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:18 volumio volumio[10333]: info: Upmpdcli Daemon Started Sep 02 04:42:18 volumio volumio[10333]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:42:18 volumio volumio[10333]: info: Spotify Successfully logged in Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:18 volumio volumio[10333]: info: [1756780938034] CoreMusicLibrary::Adding element Spotify Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:18 volumio volumio[10333]: Cannot find translation for source Jellyfin Sep 02 04:42:18 volumio volumio[10333]: Cannot find translation for source Spotify Sep 02 04:42:18 volumio go-librespot[10503]: time="2025-09-02T04:42:18+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:42:18 volumio go-librespot[10503]: time="2025-09-02T04:42:18+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:42:18 volumio go-librespot[10503]: time="2025-09-02T04:42:18+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:42:18 volumio go-librespot[10503]: time="2025-09-02T04:42:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:42:18 volumio go-librespot[10503]: time="2025-09-02T04:42:18+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:42:18 volumio sudo[10532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:18 volumio sudo[10532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:18 volumio sudo[10532]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:18 volumio volumio[10333]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:18 volumio volumio[10333]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:18 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:18 volumio volumio[10333]: info: CoreStateMachine::pushState Sep 02 04:42:18 volumio volumio[10333]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:18 volumio volumio[10333]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:18 volumio volumio[10333]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:42:18 volumio volumio[10333]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:18 volumio volumio[10333]: info: camilladsp service started and running in background, instance 1 Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:18 volumio volumio[10333]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:18 volumio volumio[10333]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:42:18 volumio volumio[10333]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:18 volumio volumio[10333]: info: FusionDsp loaded Sep 02 04:42:18 volumio volumio[10333]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:18 volumio sudo[10553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:42:18 volumio sudo[10553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:18 volumio sudo[10553]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:18 volumio volumio[10333]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:42:18 volumio volumio[10333]: info: Adding Signal Path Element [object Object] Sep 02 04:42:18 volumio volumio[10333]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:42:18 volumio volumio[10333]: info: FusionDsp - ---- installed callbackRead Sep 02 04:42:18 volumio volumio[10333]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:18 volumio volumio[10333]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:42:18 volumio volumio[10333]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:42:18 volumio volumio[10333]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:42:18 volumio volumio[10333]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:42:18 volumio volumio[10333]: errno: -2, Sep 02 04:42:18 volumio volumio[10333]: code: 'ENOENT', Sep 02 04:42:18 volumio volumio[10333]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:18 volumio volumio[10333]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:18 volumio volumio[10333]: spawnargs: [ Sep 02 04:42:18 volumio volumio[10333]: '-p', Sep 02 04:42:18 volumio volumio[10333]: 9876, Sep 02 04:42:18 volumio volumio[10333]: '-o', Sep 02 04:42:18 volumio volumio[10333]: '/tmp/camilladsp.log', Sep 02 04:42:18 volumio volumio[10333]: '-l', Sep 02 04:42:18 volumio volumio[10333]: 'warn', Sep 02 04:42:18 volumio volumio[10333]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:42:18 volumio volumio[10333]: ] Sep 02 04:42:18 volumio volumio[10333]: } Sep 02 04:42:18 volumio volumio[10333]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:19 volumio sudo[10564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:41 Sep 02 04:42:19 volumio sudo[10564]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:19 volumio mpd[10540]: Sep 02 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:42:19 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:42:19 volumio sudo[10461]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:19 volumio sudo[10433]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:19 volumio sudo[10528]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:19 volumio sudo[10564]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:19 volumio volumio-remote-updater[504]: [2025-09-02 04:42:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 04:42:19 volumio volumio-remote-updater[504]: [2025-09-02 04:42:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 04:42:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:42:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:42:19 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:19 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:42:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1482. Sep 02 04:42:19 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:42:19 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:42:19 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:19 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:21 volumio volumio[10576]: info: ------------------------------------------- Sep 02 04:42:21 volumio volumio[10576]: info: ----- Volumio3 ---- Sep 02 04:42:21 volumio volumio[10576]: info: ------------------------------------------- Sep 02 04:42:21 volumio volumio[10576]: info: ----- System startup ---- Sep 02 04:42:21 volumio volumio[10576]: info: ------------------------------------------- Sep 02 04:42:22 volumio volumio[10576]: info: MYVOLUMIO Environment detected Sep 02 04:42:22 volumio volumio[10576]: info: Plugin folders cleanup Sep 02 04:42:22 volumio volumio[10576]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category audio_interface Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category miscellanea Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category music_service Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category plugins.json Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category system_controller Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category user_interface Sep 02 04:42:22 volumio volumio[10576]: info: Scanning into folder /data/plugins/ Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category audio_interface Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category music_service Sep 02 04:42:22 volumio volumio[10576]: info: Scanning category user_interface Sep 02 04:42:22 volumio volumio[10576]: info: Plugin folders cleanup completed Sep 02 04:42:22 volumio volumio[10576]: info: ------------------------------------------- Sep 02 04:42:22 volumio volumio[10576]: info: ----- Core plugins startup ---- Sep 02 04:42:22 volumio volumio[10576]: info: ------------------------------------------- Sep 02 04:42:22 volumio volumio[10576]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:42:22 volumio volumio[10576]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:42:22 volumio volumio[10576]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:42:22 volumio volumio[10576]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:42:22 volumio volumio[10576]: info: Loading plugins from folder /data/plugins/ Sep 02 04:42:22 volumio volumio[10576]: info: Loading plugin "system"... Sep 02 04:42:22 volumio volumio[10576]: info: Loading plugin "appearance"... Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "network"... Sep 02 04:42:23 volumio volumio[10576]: info: Refreshing Cached IP Addresses Sep 02 04:42:23 volumio sudo[10641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:23 volumio sudo[10641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:23 volumio sudo[10641]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:23 volumio sudo[10643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:23 volumio sudo[10643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "services"... Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "alsa_controller"... Sep 02 04:42:23 volumio sudo[10643]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:23 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "wizard"... Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "networkfs"... Sep 02 04:42:23 volumio volumio[10576]: info: Starting Udev Watcher for removable devices Sep 02 04:42:23 volumio volumio[10576]: info: Ignoring mount for partition: boot Sep 02 04:42:23 volumio volumio[10576]: info: Ignoring mount for partition: volumio Sep 02 04:42:23 volumio volumio[10576]: info: Ignoring mount for partition: volumio_data Sep 02 04:42:23 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "upnp"... Sep 02 04:42:23 volumio volumio[10576]: info: [1756780943850] Starting Upmpd Daemon Sep 02 04:42:23 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "my_music"... Sep 02 04:42:23 volumio volumio[10576]: info: Loading plugin "mpd"... Sep 02 04:42:24 volumio volumio[10576]: info: Plugin upnp_browser is not enabled Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "alarm-clock"... Sep 02 04:42:24 volumio volumio[10576]: info: Plugin airplay_emulation is not enabled Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "last_100"... Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "webradio"... Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "i2s_dacs"... Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "volumiodiscovery"... Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** For more information see Sep 02 04:42:24 volumio node[10576]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:24 volumio volumio[10576]: *** WARNING *** For more information see Sep 02 04:42:24 volumio node[10576]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:24 volumio node[10576]: *** WARNING *** For more information see Sep 02 04:42:24 volumio node[10576]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:24 volumio node[10576]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:24 volumio node[10576]: *** WARNING *** For more information see Sep 02 04:42:24 volumio volumio[10576]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:42:24 volumio volumio[10576]: info: Discovery: Started advertising with name: Volumio Sep 02 04:42:24 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:24 volumio volumio[10576]: info: Loading plugin "jellyfin"... Sep 02 04:42:24 volumio volumio-remote-updater[504]: [2025-09-02 04:42:24] [connect] Successful connection Sep 02 04:42:25 volumio volumio[10576]: info: Loading plugin "spop"... Sep 02 04:42:26 volumio volumio[10576]: info: Loading plugin "ytcr"... Sep 02 04:42:30 volumio volumio[10576]: info: Loading plugin "outputs"... Sep 02 04:42:30 volumio volumio[10576]: info: Loading plugin "albumart"... Sep 02 04:42:30 volumio volumio[10576]: info: Plugin example_plugin is not enabled Sep 02 04:42:30 volumio volumio[10576]: info: Loading plugin "inputs"... Sep 02 04:42:30 volumio volumio[10576]: info: Loading plugin "updater_comm"... Sep 02 04:42:31 volumio volumio[10576]: info: Plugin mpdemulation is not enabled Sep 02 04:42:31 volumio volumio[10576]: info: Loading plugin "rest_api"... Sep 02 04:42:31 volumio volumio[10576]: info: Loading plugin "websocket"... Sep 02 04:42:31 volumio volumio[10576]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:42:31 volumio volumio[10576]: info: Loading plugin "fusiondsp"... Sep 02 04:42:31 volumio volumio[10576]: Forking 3 albumart workers Sep 02 04:42:31 volumio volumio[10576]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:42:31 volumio volumio[10576]: info: Loading plugin "Systeminfo"... Sep 02 04:42:31 volumio volumio[10576]: info: Loading i18n strings for locale hu Sep 02 04:42:31 volumio volumio[10576]: Updating browse sources language Sep 02 04:42:31 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::initPlayerControls Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: Express server listening on port 3000 Sep 02 04:42:32 volumio volumio[10576]: [Metrics] WebUI: 11s 1.41ms Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::resetVolumioState Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::getcurrentVolume Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:32 volumio volumio[10576]: Starting albumart workers Sep 02 04:42:32 volumio volumio[10576]: Starting albumart workers Sep 02 04:42:32 volumio volumio[10576]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:42:32 volumio volumio[10576]: Starting albumart workers Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::pushState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::updateTrackBlock Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrackBlock Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:32 volumio volumio[10576]: info: Setting Device type: Raspberry PI Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::pushState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::setRandom true Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::pushState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:32 volumio volumio[10576]: info: Listing playlists Sep 02 04:42:32 volumio volumio[10576]: info: Listing playlists Sep 02 04:42:32 volumio volumio[10576]: info: Completed loading Core Plugins Sep 02 04:42:32 volumio volumio[10576]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:32 volumio volumio[10576]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:32 volumio volumio[10576]: info: Reading ALSA contributions from plugins. Sep 02 04:42:32 volumio volumio[10576]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:32 volumio volumio[10576]: info: CoreStateMachine::pushState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:32 volumio volumio[10576]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:42:32 volumio volumio[10576]: info: Discovery: Found device Volumio Sep 02 04:42:32 volumio volumio[10576]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:32 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:32 volumio volumio[10576]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:32 volumio volumio[10576]: info: Output device has changed, restarting MPD Sep 02 04:42:33 volumio volumio[10576]: info: ___________ START PLUGINS ___________ Sep 02 04:42:33 volumio sudo[10732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:33 volumio sudo[10735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:33 volumio sudo[10732]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio sudo[10735]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio volumio[10576]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:42:33 volumio volumio[10576]: info: Creating MPD Configuration file Sep 02 04:42:33 volumio sudo[10732]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:33 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:42:33 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:33 volumio sudo[10742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:33 volumio sudo[10742]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:33 volumio volumio[10576]: info: [1756780953144] CoreMusicLibrary::Adding element Last_100 Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:33 volumio volumio[10576]: info: [1756780953149] CoreMusicLibrary::Adding element Webradio Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:33 volumio volumio[10576]: info: Initializing BBC Radios Sep 02 04:42:33 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:33 volumio sudo[10742]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:33 volumio sudo[10745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:33 volumio sudo[10745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:42:33 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:33 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:42:33 volumio volumio[10576]: info: Discovery: Getting this device information Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:33 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:33 volumio volumio[10576]: info: [1756780953268] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:33 volumio volumio[10576]: Cannot find translation for source Jellyfin Sep 02 04:42:33 volumio volumio[10576]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:42:33 volumio volumio[10576]: info: Creating Spotify config file Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio sudo[10751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:33 volumio sudo[10751]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio sudo[10751]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:33 volumio volumio[10576]: info: Loading i18n strings for locale hu Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:42:33 volumio volumio[10576]: info: Volumio Calling Home Sep 02 04:42:33 volumio volumio[10576]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:33 volumio volumio[10576]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:33 volumio volumio[10576]: info: Reading ALSA contributions from plugins. Sep 02 04:42:33 volumio volumio[10576]: info: MPD Permissions set Sep 02 04:42:33 volumio volumio[10576]: info: MPD Permissions set Sep 02 04:42:33 volumio volumio[10576]: info: Volumio called home Sep 02 04:42:33 volumio volumio[10576]: info: Spotify config file written Sep 02 04:42:33 volumio sudo[10777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:42:33 volumio sudo[10777]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:33 volumio volumio[10576]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:42:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:33 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:42:33 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:33 volumio volumio[10576]: info: No need to fix Spotify hosts Sep 02 04:42:33 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:42:33 volumio go-librespot[10785]: go-librespot daemon starting... Sep 02 04:42:33 volumio sudo[10777]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:33 volumio go-librespot[10785]: time="2025-09-02T04:42:33+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:42:33 volumio go-librespot[10785]: time="2025-09-02T04:42:33+02:00" level=debug msg="app state loaded" Sep 02 04:42:33 volumio go-librespot[10785]: time="2025-09-02T04:42:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:42:34 volumio sudo[10799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:34 volumio sudo[10799]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio sudo[10799]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:34 volumio sudo[10801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:34 volumio sudo[10801]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio sudo[10801]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=info msg="zeroconf server listening on port 43889" Sep 02 04:42:34 volumio volumio[10576]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:34 volumio volumio[10576]: SPOTIFY: BQD-vcNADpGr8D16mntTLCOhV62NZRKZAIOZEiewpvhe2-b3nAezbvDArvQMReJYxDgi4N_CUwhngydk565HDGbfXvbydUeLRhnDfmBkc22qicP5ArztRe_MKXxAzOopmFbKo9IJxXZpiSH5ITFzb-NGeT34CprFMHV0769OgXSdR3FwVyV0v7MnKyJwnVc9uT8e-7QrH0z2MCXOZGrAQI0ayrmnU1TWa33SZQP6xTsFJwBfSmr8cWfKmMOHQo7oZGNZEA_Vnvtz1yudbixOaN8NsD7roZljz906lMm7MbppBjc Sep 02 04:42:34 volumio volumio[10576]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:34 volumio volumio[10576]: info: New Spotify access token = BQD-vcNADpGr8D16mntTLCOhV62NZRKZAIOZEiewpvhe2-b3nAezbvDArvQMReJYxDgi4N_CUwhngydk565HDGbfXvbydUeLRhnDfmBkc22qicP5ArztRe_MKXxAzOopmFbKo9IJxXZpiSH5ITFzb-NGeT34CprFMHV0769OgXSdR3FwVyV0v7MnKyJwnVc9uT8e-7QrH0z2MCXOZGrAQI0ayrmnU1TWa33SZQP6xTsFJwBfSmr8cWfKmMOHQo7oZGNZEA_Vnvtz1yudbixOaN8NsD7roZljz906lMm7MbppBjc Sep 02 04:42:34 volumio volumio[10576]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="obtained new client token: AACdTYNqVfbIs04PZy4M1QM/2LWDRWTWVyJjIKVHsUuOyEgQTUCCmcesYsXtdMSmbvi5zz7vt5OzOvia9YQVmyfHY1f+w/gBZnzrfsD31d9o9mKxsNaSTS0YW9h2B+k7tzPTkwfVD3fO7g733dMq1l4t0Vy/j6h6WGYptgnYjEbnFooLRvpH+5EazyHGHgYxZOAtT+jj5R1lv360jG2tuUYOwt4kg/J+RxB/CaiJBaornMlsuaGfWfjN" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:34 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:34 volumio volumio[10576]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:34 volumio volumio[10576]: info: Output device has changed, restarting MPD Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="completed keyexchange" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="completed challenge" Sep 02 04:42:34 volumio sudo[10805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:34 volumio sudo[10805]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:42:34 volumio sudo[10805]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:34 volumio volumio[10576]: info: MPD Permissions set Sep 02 04:42:34 volumio sudo[10808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:34 volumio sudo[10808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:34 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:34 volumio sudo[10811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:42:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:34 volumio sudo[10811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:42:34 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:34 volumio sudo[10811]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="dealer connection opened" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=trace msg="received accesspoint ping" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="received connection id: NGVjMGZjZjItNmUx...OTJCQTI0RjFGQg==" Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:42:34 volumio volumio[10576]: info: Upmpdcli Daemon Started Sep 02 04:42:34 volumio volumio[10576]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:42:34 volumio volumio[10576]: info: Spotify Successfully logged in Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:34 volumio volumio[10576]: info: [1756780954639] CoreMusicLibrary::Adding element Spotify Sep 02 04:42:34 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:34 volumio volumio[10576]: Cannot find translation for source Jellyfin Sep 02 04:42:34 volumio volumio[10576]: Cannot find translation for source Spotify Sep 02 04:42:34 volumio go-librespot[10785]: time="2025-09-02T04:42:34+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:42:34 volumio sudo[10812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:34 volumio sudo[10812]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:34 volumio sudo[10812]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:35 volumio volumio[10576]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:42:35 volumio volumio[10576]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:35 volumio volumio[10576]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:35 volumio volumio[10576]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:35 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:35 volumio volumio[10576]: info: CoreStateMachine::pushState Sep 02 04:42:35 volumio volumio[10576]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:35 volumio volumio[10576]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:35 volumio volumio[10576]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:35 volumio volumio[10576]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:42:35 volumio volumio[10576]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:35 volumio volumio[10576]: info: camilladsp service started and running in background, instance 1 Sep 02 04:42:35 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:35 volumio volumio[10576]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:35 volumio volumio[10576]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:42:35 volumio volumio[10576]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:35 volumio volumio[10576]: info: FusionDsp loaded Sep 02 04:42:35 volumio volumio[10576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:35 volumio sudo[10834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:42:35 volumio sudo[10834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:35 volumio sudo[10834]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:35 volumio volumio[10576]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:42:35 volumio volumio[10576]: info: Adding Signal Path Element [object Object] Sep 02 04:42:35 volumio volumio[10576]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:42:35 volumio volumio[10576]: info: FusionDsp - ---- installed callbackRead Sep 02 04:42:35 volumio volumio[10576]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:35 volumio volumio[10576]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:42:35 volumio volumio[10576]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:42:35 volumio volumio[10576]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:42:35 volumio volumio[10576]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:42:35 volumio volumio[10576]: errno: -2, Sep 02 04:42:35 volumio volumio[10576]: code: 'ENOENT', Sep 02 04:42:35 volumio volumio[10576]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:35 volumio volumio[10576]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:35 volumio volumio[10576]: spawnargs: [ Sep 02 04:42:35 volumio volumio[10576]: '-p', Sep 02 04:42:35 volumio volumio[10576]: 9876, Sep 02 04:42:35 volumio volumio[10576]: '-o', Sep 02 04:42:35 volumio volumio[10576]: '/tmp/camilladsp.log', Sep 02 04:42:35 volumio volumio[10576]: '-l', Sep 02 04:42:35 volumio volumio[10576]: 'warn', Sep 02 04:42:35 volumio volumio[10576]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:42:35 volumio volumio[10576]: ] Sep 02 04:42:35 volumio volumio[10576]: } Sep 02 04:42:35 volumio volumio[10576]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:36 volumio mpd[10821]: Sep 02 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:42:36 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:42:36 volumio sudo[10845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:41 Sep 02 04:42:36 volumio sudo[10745]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:36 volumio sudo[10845]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:36 volumio sudo[10735]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:36 volumio sudo[10808]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:36 volumio sudo[10845]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:42:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:42:36 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:36 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:36 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:42:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1483. Sep 02 04:42:36 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:42:36 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:42:36 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:37 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:37 volumio volumio-remote-updater[504]: [2025-09-02 04:42:37] [info] asio async_connect error: system:111 (Connection refused) Sep 02 04:42:37 volumio volumio-remote-updater[504]: [2025-09-02 04:42:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 04:42:37 volumio volumio-remote-updater[504]: [2025-09-02 04:42:37] [error] handle_connect error: Underlying Transport Error Sep 02 04:42:38 volumio volumio[10858]: info: ------------------------------------------- Sep 02 04:42:38 volumio volumio[10858]: info: ----- Volumio3 ---- Sep 02 04:42:38 volumio volumio[10858]: info: ------------------------------------------- Sep 02 04:42:38 volumio volumio[10858]: info: ----- System startup ---- Sep 02 04:42:38 volumio volumio[10858]: info: ------------------------------------------- Sep 02 04:42:39 volumio volumio[10858]: info: MYVOLUMIO Environment detected Sep 02 04:42:39 volumio volumio[10858]: info: Plugin folders cleanup Sep 02 04:42:39 volumio volumio[10858]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category audio_interface Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category miscellanea Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category music_service Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category plugins.json Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category system_controller Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category user_interface Sep 02 04:42:39 volumio volumio[10858]: info: Scanning into folder /data/plugins/ Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category audio_interface Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category music_service Sep 02 04:42:39 volumio volumio[10858]: info: Scanning category user_interface Sep 02 04:42:39 volumio volumio[10858]: info: Plugin folders cleanup completed Sep 02 04:42:39 volumio volumio[10858]: info: ------------------------------------------- Sep 02 04:42:39 volumio volumio[10858]: info: ----- Core plugins startup ---- Sep 02 04:42:39 volumio volumio[10858]: info: ------------------------------------------- Sep 02 04:42:39 volumio volumio[10858]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:42:39 volumio volumio[10858]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:42:39 volumio volumio[10858]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:42:39 volumio volumio[10858]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:42:39 volumio volumio[10858]: info: Loading plugins from folder /data/plugins/ Sep 02 04:42:39 volumio volumio[10858]: info: Loading plugin "system"... Sep 02 04:42:39 volumio volumio[10858]: info: Loading plugin "appearance"... Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "network"... Sep 02 04:42:40 volumio volumio[10858]: info: Refreshing Cached IP Addresses Sep 02 04:42:40 volumio sudo[10885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:40 volumio sudo[10885]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:40 volumio sudo[10885]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:40 volumio sudo[10887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:40 volumio sudo[10887]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "services"... Sep 02 04:42:40 volumio sudo[10887]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "alsa_controller"... Sep 02 04:42:40 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "wizard"... Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "networkfs"... Sep 02 04:42:40 volumio volumio[10858]: info: Starting Udev Watcher for removable devices Sep 02 04:42:40 volumio volumio[10858]: info: Ignoring mount for partition: boot Sep 02 04:42:40 volumio volumio[10858]: info: Ignoring mount for partition: volumio Sep 02 04:42:40 volumio volumio[10858]: info: Ignoring mount for partition: volumio_data Sep 02 04:42:40 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "upnp"... Sep 02 04:42:40 volumio volumio[10858]: info: [1756780960831] Starting Upmpd Daemon Sep 02 04:42:40 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "my_music"... Sep 02 04:42:40 volumio volumio[10858]: info: Loading plugin "mpd"... Sep 02 04:42:41 volumio volumio[10858]: info: Plugin upnp_browser is not enabled Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "alarm-clock"... Sep 02 04:42:41 volumio volumio[10858]: info: Plugin airplay_emulation is not enabled Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "last_100"... Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "webradio"... Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "i2s_dacs"... Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "volumiodiscovery"... Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** For more information see Sep 02 04:42:41 volumio node[10858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:41 volumio volumio[10858]: *** WARNING *** For more information see Sep 02 04:42:41 volumio node[10858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:41 volumio node[10858]: *** WARNING *** For more information see Sep 02 04:42:41 volumio node[10858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:41 volumio node[10858]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:41 volumio node[10858]: *** WARNING *** For more information see Sep 02 04:42:41 volumio volumio[10858]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:42:41 volumio volumio[10858]: info: Discovery: Started advertising with name: Volumio Sep 02 04:42:41 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:41 volumio volumio[10858]: info: Loading plugin "jellyfin"... Sep 02 04:42:42 volumio volumio[10858]: info: Loading plugin "spop"... Sep 02 04:42:43 volumio volumio[10858]: info: Loading plugin "ytcr"... Sep 02 04:42:47 volumio volumio-remote-updater[504]: [2025-09-02 04:42:47] [connect] Successful connection Sep 02 04:42:47 volumio volumio[10858]: info: Loading plugin "outputs"... Sep 02 04:42:47 volumio volumio[10858]: info: Loading plugin "albumart"... Sep 02 04:42:47 volumio volumio[10858]: info: Plugin example_plugin is not enabled Sep 02 04:42:47 volumio volumio[10858]: info: Loading plugin "inputs"... Sep 02 04:42:47 volumio volumio[10858]: info: Loading plugin "updater_comm"... Sep 02 04:42:48 volumio volumio[10858]: info: Plugin mpdemulation is not enabled Sep 02 04:42:48 volumio volumio[10858]: info: Loading plugin "rest_api"... Sep 02 04:42:48 volumio volumio[10858]: info: Loading plugin "websocket"... Sep 02 04:42:48 volumio volumio[10858]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:42:48 volumio volumio[10858]: info: Loading plugin "fusiondsp"... Sep 02 04:42:48 volumio volumio[10858]: Forking 3 albumart workers Sep 02 04:42:48 volumio volumio[10858]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:42:48 volumio volumio[10858]: info: Loading plugin "Systeminfo"... Sep 02 04:42:48 volumio volumio[10858]: info: Loading i18n strings for locale hu Sep 02 04:42:48 volumio volumio[10858]: Updating browse sources language Sep 02 04:42:48 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::initPlayerControls Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: Express server listening on port 3000 Sep 02 04:42:49 volumio volumio[10858]: [Metrics] WebUI: 11s 10.19ms Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::resetVolumioState Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::getcurrentVolume Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:49 volumio volumio[10858]: Starting albumart workers Sep 02 04:42:49 volumio volumio[10858]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:42:49 volumio volumio[10858]: Starting albumart workers Sep 02 04:42:49 volumio volumio[10858]: Starting albumart workers Sep 02 04:42:49 volumio volumio-remote-updater[504]: [2025-09-02 04:42:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756780967 101 Sep 02 04:42:49 volumio volumio[10858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:49 volumio volumio[10858]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::pushState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::updateTrackBlock Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrackBlock Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::pushState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::setRandom true Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::pushState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:49 volumio volumio[10858]: info: Setting Device type: Raspberry PI Sep 02 04:42:49 volumio volumio[10858]: info: Listing playlists Sep 02 04:42:49 volumio volumio[10858]: info: Listing playlists Sep 02 04:42:49 volumio volumio[10858]: info: Completed loading Core Plugins Sep 02 04:42:49 volumio volumio[10858]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:49 volumio volumio[10858]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:42:49 volumio volumio[10858]: info: Discovery: Found device Volumio Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:49 volumio volumio[10858]: info: Reading ALSA contributions from plugins. Sep 02 04:42:49 volumio volumio[10858]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:49 volumio volumio[10858]: info: CoreStateMachine::pushState Sep 02 04:42:49 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:49 volumio volumio[10858]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:49 volumio volumio[10858]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:49 volumio volumio[10858]: info: Output device has changed, restarting MPD Sep 02 04:42:50 volumio sudo[10964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:50 volumio sudo[10964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio volumio[10858]: info: ___________ START PLUGINS ___________ Sep 02 04:42:50 volumio volumio[10858]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:42:50 volumio volumio[10858]: info: Creating MPD Configuration file Sep 02 04:42:50 volumio sudo[10964]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:50 volumio sudo[10971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:50 volumio sudo[10971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio sudo[10977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:50 volumio sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio sudo[10977]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:50 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:50 volumio volumio[10858]: info: [1756780970151] CoreMusicLibrary::Adding element Last_100 Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:50 volumio volumio[10858]: info: [1756780970158] CoreMusicLibrary::Adding element Webradio Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:50 volumio sudo[10985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:50 volumio sudo[10985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio volumio[10858]: info: Initializing BBC Radios Sep 02 04:42:50 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:50 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:50 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:42:50 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:50 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:42:50 volumio volumio[10858]: info: Discovery: Getting this device information Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:50 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:50 volumio volumio[10858]: info: [1756780970312] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:50 volumio volumio[10858]: Cannot find translation for source Jellyfin Sep 02 04:42:50 volumio volumio[10858]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:42:50 volumio volumio[10858]: info: Creating Spotify config file Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:50 volumio sudo[10999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:50 volumio sudo[10999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio sudo[10999]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:50 volumio volumio[10858]: info: Loading i18n strings for locale hu Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:50 volumio volumio[10858]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:42:50 volumio volumio[10858]: info: Volumio Calling Home Sep 02 04:42:50 volumio volumio[10858]: info: Preparing to generate the ALSA configuration file Sep 02 04:42:50 volumio volumio[10858]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:42:50 volumio volumio[10858]: info: Reading ALSA contributions from plugins. Sep 02 04:42:50 volumio volumio[10858]: info: MPD Permissions set Sep 02 04:42:50 volumio volumio[10858]: info: MPD Permissions set Sep 02 04:42:50 volumio volumio[10858]: info: Spotify config file written Sep 02 04:42:50 volumio sudo[11024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:42:50 volumio volumio[10858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 02 04:42:50 volumio sudo[11024]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio volumio[10858]: info: Volumio called home Sep 02 04:42:50 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:42:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:50 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:42:50 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:42:50 volumio sudo[11028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:50 volumio sudo[11028]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:50 volumio sudo[11028]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:50 volumio go-librespot[11027]: go-librespot daemon starting... Sep 02 04:42:50 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:50 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:42:51 volumio sudo[11024]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:51 volumio sudo[11031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:51 volumio sudo[11031]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="app state loaded" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:42:51 volumio sudo[11031]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: No need to fix Spotify hosts Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=info msg="zeroconf server listening on port 34189" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="obtained new client token: AACQUsaMB7zAovIiduySQXfDaHJC6DuMZMDw8XZjBXWjBqzWGB4CZCfXQWYsdlfu17l2o5iU2/a9xDBmCQ0sN7YDUaGcX95A6DVYqWqCrUtmyzaTCUuZH08zyEmhSJMjd1IN/3z55pJRxCog36/ZKfHYLE8tL/uaWfMes4IM0oSwHIHQ/k29+UtE7P/q3Ad9xVAIPWB9d59aLwi0BiCe2JFvwpEBf281zziED3OyKFa8uP8u5ZrqFcex+8I=" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="completed keyexchange" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="completed challenge" Sep 02 04:42:51 volumio volumio[10858]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:51 volumio volumio[10858]: SPOTIFY: BQASlrxLBgjrPrn56dZ6GpZ5-JfUdNmyU3-8z7cJBkMJLsw_qCFi07LusuTHEdKaDfWtYMRmbKA3x2jx1H123S2fZXZ664O70WuQtH8AoYvVdHmO4cNvwBURMLO99FwW6nvR4p7BNiXqv6sHTCk-wKdnH6_TY66HnSez1fTYk1C5EKMO2FCurTJ61ky0wewdA6U824VrCBLH5jTscnAWQLmumhXRgLEKNhMRXh9AWtcR4Fsv0AcUyIm5qAzAyHPYFuD3o1LpvzSxy1_ZrCMIG4iosBFiev71pQaj0ldDXNEhGAo Sep 02 04:42:51 volumio volumio[10858]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:42:51 volumio volumio[10858]: info: New Spotify access token = BQASlrxLBgjrPrn56dZ6GpZ5-JfUdNmyU3-8z7cJBkMJLsw_qCFi07LusuTHEdKaDfWtYMRmbKA3x2jx1H123S2fZXZ664O70WuQtH8AoYvVdHmO4cNvwBURMLO99FwW6nvR4p7BNiXqv6sHTCk-wKdnH6_TY66HnSez1fTYk1C5EKMO2FCurTJ61ky0wewdA6U824VrCBLH5jTscnAWQLmumhXRgLEKNhMRXh9AWtcR4Fsv0AcUyIm5qAzAyHPYFuD3o1LpvzSxy1_ZrCMIG4iosBFiev71pQaj0ldDXNEhGAo Sep 02 04:42:51 volumio volumio[10858]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:51 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:51 volumio volumio[10858]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:42:51 volumio volumio[10858]: info: Output device has changed, restarting MPD Sep 02 04:42:51 volumio sudo[11050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:42:51 volumio sudo[11050]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:51 volumio sudo[11050]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:42:51 volumio sudo[11053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:42:51 volumio sudo[11053]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:51 volumio volumio[10858]: info: MPD Permissions set Sep 02 04:42:51 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:42:51 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:42:51 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="dealer connection opened" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=trace msg="received accesspoint ping" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="received connection id: NjZhOGM0ZjEtMDZk...OEM1RjZCOUMzMw==" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:42:51 volumio sudo[11057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:42:51 volumio sudo[11057]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:51 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:51 volumio sudo[11057]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:51 volumio volumio[10858]: info: Upmpdcli Daemon Started Sep 02 04:42:51 volumio volumio[10858]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:42:51 volumio volumio[10858]: info: Spotify Successfully logged in Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:42:51 volumio volumio[10858]: info: [1756780971844] CoreMusicLibrary::Adding element Spotify Sep 02 04:42:51 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:42:51 volumio volumio[10858]: Cannot find translation for source Jellyfin Sep 02 04:42:51 volumio go-librespot[11027]: time="2025-09-02T04:42:51+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:42:51 volumio volumio[10858]: Cannot find translation for source Spotify Sep 02 04:42:51 volumio sudo[11056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:42:51 volumio sudo[11056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:51 volumio sudo[11056]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:52 volumio volumio[10858]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:42:52 volumio volumio[10858]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:42:52 volumio volumio[10858]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:42:52 volumio volumio[10858]: info: CoreCommandRouter::volumioGetState Sep 02 04:42:52 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:52 volumio volumio[10858]: info: CoreStateMachine::pushState Sep 02 04:42:52 volumio volumio[10858]: info: CorePlayQueue::getTrack 0 Sep 02 04:42:52 volumio volumio[10858]: info: CoreCommandRouter::volumioPushState Sep 02 04:42:52 volumio volumio[10858]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:52 volumio volumio[10858]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:42:52 volumio volumio[10858]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:42:52 volumio volumio[10858]: info: camilladsp service started and running in background, instance 1 Sep 02 04:42:52 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:42:52 volumio volumio[10858]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:52 volumio volumio[10858]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:42:52 volumio volumio[10858]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:42:52 volumio volumio[10858]: info: FusionDsp loaded Sep 02 04:42:52 volumio volumio[10858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:42:52 volumio sudo[11077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:42:52 volumio sudo[11077]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:52 volumio volumio[10858]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:42:52 volumio volumio[10858]: info: Adding Signal Path Element [object Object] Sep 02 04:42:52 volumio volumio[10858]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:42:52 volumio volumio[10858]: info: FusionDsp - ---- installed callbackRead Sep 02 04:42:52 volumio volumio[10858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:52 volumio sudo[11077]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:52 volumio volumio[10858]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:42:52 volumio volumio[10858]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:42:52 volumio volumio[10858]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:42:52 volumio volumio[10858]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:42:52 volumio volumio[10858]: errno: -2, Sep 02 04:42:52 volumio volumio[10858]: code: 'ENOENT', Sep 02 04:42:52 volumio volumio[10858]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:52 volumio volumio[10858]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:42:52 volumio volumio[10858]: spawnargs: [ Sep 02 04:42:52 volumio volumio[10858]: '-p', Sep 02 04:42:52 volumio volumio[10858]: 9876, Sep 02 04:42:52 volumio volumio[10858]: '-o', Sep 02 04:42:52 volumio volumio[10858]: '/tmp/camilladsp.log', Sep 02 04:42:52 volumio volumio[10858]: '-l', Sep 02 04:42:52 volumio volumio[10858]: 'warn', Sep 02 04:42:52 volumio volumio[10858]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:42:52 volumio volumio[10858]: ] Sep 02 04:42:52 volumio volumio[10858]: } Sep 02 04:42:52 volumio volumio[10858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:42:53 volumio sudo[11088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:41 Sep 02 04:42:53 volumio sudo[11088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:53 volumio mpd[11064]: Sep 02 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:42:53 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:42:53 volumio sudo[10971]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:53 volumio sudo[11053]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:53 volumio sudo[10985]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:53 volumio sudo[11088]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:53 volumio volumio-remote-updater[504]: [2025-09-02 04:42:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 04:42:53 volumio volumio-remote-updater[504]: [2025-09-02 04:42:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 04:42:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:42:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:42:53 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:53 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:53 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:42:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1484. Sep 02 04:42:53 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:42:53 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:42:53 volumio systemd[1]: Started dynamicswap service. Sep 02 04:42:54 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:42:55 volumio volumio[11100]: info: ------------------------------------------- Sep 02 04:42:55 volumio volumio[11100]: info: ----- Volumio3 ---- Sep 02 04:42:55 volumio volumio[11100]: info: ------------------------------------------- Sep 02 04:42:55 volumio volumio[11100]: info: ----- System startup ---- Sep 02 04:42:55 volumio volumio[11100]: info: ------------------------------------------- Sep 02 04:42:56 volumio volumio[11100]: info: MYVOLUMIO Environment detected Sep 02 04:42:56 volumio volumio[11100]: info: Plugin folders cleanup Sep 02 04:42:56 volumio volumio[11100]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category audio_interface Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category miscellanea Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category music_service Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category plugins.json Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category system_controller Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category user_interface Sep 02 04:42:56 volumio volumio[11100]: info: Scanning into folder /data/plugins/ Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category audio_interface Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category music_service Sep 02 04:42:56 volumio volumio[11100]: info: Scanning category user_interface Sep 02 04:42:56 volumio volumio[11100]: info: Plugin folders cleanup completed Sep 02 04:42:56 volumio volumio[11100]: info: ------------------------------------------- Sep 02 04:42:56 volumio volumio[11100]: info: ----- Core plugins startup ---- Sep 02 04:42:56 volumio volumio[11100]: info: ------------------------------------------- Sep 02 04:42:56 volumio volumio[11100]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:42:56 volumio volumio[11100]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:42:56 volumio volumio[11100]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:42:56 volumio volumio[11100]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:42:56 volumio volumio[11100]: info: Loading plugins from folder /data/plugins/ Sep 02 04:42:56 volumio volumio[11100]: info: Loading plugin "system"... Sep 02 04:42:56 volumio volumio[11100]: info: Loading plugin "appearance"... Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "network"... Sep 02 04:42:57 volumio volumio[11100]: info: Refreshing Cached IP Addresses Sep 02 04:42:57 volumio sudo[11128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:42:57 volumio sudo[11128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:57 volumio sudo[11128]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:57 volumio sudo[11130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:42:57 volumio sudo[11130]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "services"... Sep 02 04:42:57 volumio sudo[11130]: pam_unix(sudo:session): session closed for user root Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "alsa_controller"... Sep 02 04:42:57 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "wizard"... Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "networkfs"... Sep 02 04:42:57 volumio volumio[11100]: info: Starting Udev Watcher for removable devices Sep 02 04:42:57 volumio volumio[11100]: info: Ignoring mount for partition: boot Sep 02 04:42:57 volumio volumio[11100]: info: Ignoring mount for partition: volumio Sep 02 04:42:57 volumio volumio[11100]: info: Ignoring mount for partition: volumio_data Sep 02 04:42:57 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "upnp"... Sep 02 04:42:57 volumio volumio[11100]: info: [1756780977856] Starting Upmpd Daemon Sep 02 04:42:57 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "my_music"... Sep 02 04:42:57 volumio volumio[11100]: info: Loading plugin "mpd"... Sep 02 04:42:58 volumio volumio[11100]: info: Plugin upnp_browser is not enabled Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "alarm-clock"... Sep 02 04:42:58 volumio volumio[11100]: info: Plugin airplay_emulation is not enabled Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "last_100"... Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "webradio"... Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "i2s_dacs"... Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "volumiodiscovery"... Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** For more information see Sep 02 04:42:58 volumio node[11100]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:58 volumio volumio[11100]: *** WARNING *** For more information see Sep 02 04:42:58 volumio node[11100]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:58 volumio node[11100]: *** WARNING *** For more information see Sep 02 04:42:58 volumio node[11100]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:42:58 volumio node[11100]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:42:58 volumio node[11100]: *** WARNING *** For more information see Sep 02 04:42:58 volumio volumio[11100]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:42:58 volumio volumio[11100]: info: Discovery: Started advertising with name: Volumio Sep 02 04:42:58 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:42:58 volumio volumio[11100]: info: Loading plugin "jellyfin"... Sep 02 04:42:58 volumio volumio-remote-updater[504]: [2025-09-02 04:42:58] [connect] Successful connection Sep 02 04:42:59 volumio volumio[11100]: info: Loading plugin "spop"... Sep 02 04:43:00 volumio volumio[11100]: info: Loading plugin "ytcr"... Sep 02 04:43:04 volumio volumio[11100]: info: Loading plugin "outputs"... Sep 02 04:43:04 volumio volumio[11100]: info: Loading plugin "albumart"... Sep 02 04:43:04 volumio volumio[11100]: info: Plugin example_plugin is not enabled Sep 02 04:43:04 volumio volumio[11100]: info: Loading plugin "inputs"... Sep 02 04:43:04 volumio volumio[11100]: info: Loading plugin "updater_comm"... Sep 02 04:43:05 volumio volumio[11100]: info: Plugin mpdemulation is not enabled Sep 02 04:43:05 volumio volumio[11100]: info: Loading plugin "rest_api"... Sep 02 04:43:05 volumio volumio[11100]: info: Loading plugin "websocket"... Sep 02 04:43:05 volumio volumio[11100]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:43:05 volumio volumio[11100]: info: Loading plugin "fusiondsp"... Sep 02 04:43:05 volumio volumio[11100]: Forking 3 albumart workers Sep 02 04:43:05 volumio volumio[11100]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:43:05 volumio volumio[11100]: info: Loading plugin "Systeminfo"... Sep 02 04:43:05 volumio volumio[11100]: info: Loading i18n strings for locale hu Sep 02 04:43:05 volumio volumio[11100]: Updating browse sources language Sep 02 04:43:05 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::initPlayerControls Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: Express server listening on port 3000 Sep 02 04:43:06 volumio volumio[11100]: [Metrics] WebUI: 10s 982.92ms Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::resetVolumioState Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::getcurrentVolume Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:06 volumio volumio[11100]: Starting albumart workers Sep 02 04:43:06 volumio volumio[11100]: Starting albumart workers Sep 02 04:43:06 volumio volumio[11100]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:43:06 volumio volumio[11100]: Starting albumart workers Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:06 volumio volumio[11100]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::pushState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::updateTrackBlock Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrackBlock Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::pushState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::setRandom true Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::pushState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:06 volumio volumio[11100]: info: Setting Device type: Raspberry PI Sep 02 04:43:06 volumio volumio[11100]: info: Listing playlists Sep 02 04:43:06 volumio volumio[11100]: info: Listing playlists Sep 02 04:43:06 volumio volumio[11100]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:43:06 volumio volumio[11100]: info: Discovery: Found device Volumio Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: Completed loading Core Plugins Sep 02 04:43:06 volumio volumio[11100]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:06 volumio volumio[11100]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:06 volumio volumio[11100]: info: Reading ALSA contributions from plugins. Sep 02 04:43:06 volumio volumio[11100]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:06 volumio volumio[11100]: info: CoreStateMachine::pushState Sep 02 04:43:06 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:06 volumio volumio[11100]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:06 volumio volumio[11100]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:06 volumio volumio[11100]: info: Output device has changed, restarting MPD Sep 02 04:43:06 volumio sudo[11208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:06 volumio sudo[11208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:06 volumio volumio[11100]: info: ___________ START PLUGINS ___________ Sep 02 04:43:07 volumio sudo[11208]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:07 volumio volumio[11100]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:43:07 volumio volumio[11100]: info: Creating MPD Configuration file Sep 02 04:43:07 volumio sudo[11213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:07 volumio sudo[11213]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:07 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:07 volumio volumio[11100]: info: [1756780987091] CoreMusicLibrary::Adding element Last_100 Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:07 volumio volumio[11100]: info: [1756780987098] CoreMusicLibrary::Adding element Webradio Sep 02 04:43:07 volumio sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:07 volumio sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:07 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:07 volumio sudo[11222]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:07 volumio volumio[11100]: info: Initializing BBC Radios Sep 02 04:43:07 volumio sudo[11228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:07 volumio sudo[11228]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:07 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:43:07 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:07 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:43:07 volumio volumio[11100]: info: Discovery: Getting this device information Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:07 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:07 volumio volumio[11100]: info: [1756780987253] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:07 volumio volumio[11100]: Cannot find translation for source Jellyfin Sep 02 04:43:07 volumio volumio[11100]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:43:07 volumio volumio[11100]: info: Creating Spotify config file Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio sudo[11238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:07 volumio sudo[11238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:07 volumio sudo[11238]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:07 volumio volumio[11100]: info: Loading i18n strings for locale hu Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:43:07 volumio volumio[11100]: info: Volumio Calling Home Sep 02 04:43:07 volumio volumio[11100]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:07 volumio volumio[11100]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:07 volumio volumio[11100]: info: Reading ALSA contributions from plugins. Sep 02 04:43:07 volumio volumio[11100]: info: MPD Permissions set Sep 02 04:43:07 volumio volumio[11100]: info: MPD Permissions set Sep 02 04:43:07 volumio volumio[11100]: info: Spotify config file written Sep 02 04:43:07 volumio sudo[11253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:43:07 volumio sudo[11253]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:07 volumio volumio[11100]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 02 04:43:07 volumio volumio[11100]: info: Volumio called home Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:43:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:07 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:43:07 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:07 volumio volumio[11100]: info: No need to fix Spotify hosts Sep 02 04:43:07 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:43:07 volumio go-librespot[11266]: go-librespot daemon starting... Sep 02 04:43:07 volumio sudo[11253]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:07 volumio go-librespot[11266]: time="2025-09-02T04:43:07+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:43:07 volumio go-librespot[11266]: time="2025-09-02T04:43:07+02:00" level=debug msg="app state loaded" Sep 02 04:43:07 volumio go-librespot[11266]: time="2025-09-02T04:43:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:43:08 volumio sudo[11282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:43:08 volumio sudo[11282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio sudo[11282]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:08 volumio sudo[11284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:43:08 volumio sudo[11284]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 02 04:43:08 volumio sudo[11284]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=info msg="zeroconf server listening on port 39399" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="obtained new client token: AADNHEw0lTCYLZaDJufGtLLUZEOInC7hKoXqS71lLNtPlUzjqsg1q0BuFpKE+Wdu/j4qUZmYKhU1dfEr7Gk6M5yFndiXZFYA0+8KgOy+stBe0i+F+mmp5cCma9NK66o4cRUkN3i6AJK/4c7yrxDMqark4cgTR3ylT9KJ31f1EFrBMGg2pJViQHEcCny9mhC07eojfbZHCaeUKO+iIcAUloCZ3vQTP40Rbw3EwgIC80C+5Er7NBn1CyQs" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="completed keyexchange" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="completed challenge" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:08 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:43:08 volumio volumio[11100]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:08 volumio volumio[11100]: SPOTIFY: BQDowzRsTjyRHJgExVluTqOvtpWwbz1tPVydYPfRIl3nODsRHOnjZ6CgDitUiVEjoZXDQCAE3ZJPIdn-prR0J6Bh6tURzPBiyjleFswFu-pf6ebBWYFNO5upEej8bsGy4jTXMg7q-8wyjw4Xu3iAMI-uuWcHCk1YqN5AI_oldZD9bjvS4WH3RXuNRpnp7xulyT5GyW1PU9UYSq_Wz69R-yjFoiNOWre_dcji7RhDPVg0fza0cZeizeDNA7y0gsn79oUKHjBPD9-yWAf57kTxRFotaU4AIJLmaH9WeIK4Tqd7YuA Sep 02 04:43:08 volumio volumio[11100]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:08 volumio volumio[11100]: info: New Spotify access token = BQDowzRsTjyRHJgExVluTqOvtpWwbz1tPVydYPfRIl3nODsRHOnjZ6CgDitUiVEjoZXDQCAE3ZJPIdn-prR0J6Bh6tURzPBiyjleFswFu-pf6ebBWYFNO5upEej8bsGy4jTXMg7q-8wyjw4Xu3iAMI-uuWcHCk1YqN5AI_oldZD9bjvS4WH3RXuNRpnp7xulyT5GyW1PU9UYSq_Wz69R-yjFoiNOWre_dcji7RhDPVg0fza0cZeizeDNA7y0gsn79oUKHjBPD9-yWAf57kTxRFotaU4AIJLmaH9WeIK4Tqd7YuA Sep 02 04:43:08 volumio volumio[11100]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:43:08 volumio volumio[11100]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:08 volumio volumio[11100]: info: Output device has changed, restarting MPD Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="dealer connection opened" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=trace msg="received accesspoint ping" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="received connection id: YTAxMjUwZTgtZWVm...MDg4NDYzRkM3NA==" Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:43:08 volumio sudo[11288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:08 volumio sudo[11288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio sudo[11288]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:08 volumio sudo[11290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:08 volumio sudo[11290]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio volumio[11100]: info: MPD Permissions set Sep 02 04:43:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:08 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:08 volumio go-librespot[11266]: time="2025-09-02T04:43:08+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:43:08 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:08 volumio sudo[11296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio sudo[11296]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio sudo[11296]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:08 volumio volumio[11100]: info: Upmpdcli Daemon Started Sep 02 04:43:08 volumio volumio[11100]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:43:08 volumio volumio[11100]: info: Spotify Successfully logged in Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:08 volumio volumio[11100]: info: [1756780988876] CoreMusicLibrary::Adding element Spotify Sep 02 04:43:08 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:08 volumio volumio[11100]: Cannot find translation for source Jellyfin Sep 02 04:43:08 volumio volumio[11100]: Cannot find translation for source Spotify Sep 02 04:43:08 volumio sudo[11293]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:08 volumio sudo[11293]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:08 volumio sudo[11293]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:09 volumio volumio[11100]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:09 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:09 volumio volumio[11100]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:09 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:09 volumio volumio[11100]: info: CoreStateMachine::pushState Sep 02 04:43:09 volumio volumio[11100]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:09 volumio volumio[11100]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:43:09 volumio volumio[11100]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:43:09 volumio volumio[11100]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:43:09 volumio volumio[11100]: info: camilladsp service started and running in background, instance 1 Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:09 volumio volumio[11100]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:43:09 volumio volumio[11100]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:43:09 volumio volumio[11100]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:43:09 volumio volumio[11100]: info: FusionDsp loaded Sep 02 04:43:09 volumio volumio[11100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:43:09 volumio sudo[11319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:43:09 volumio sudo[11319]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:09 volumio sudo[11319]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:09 volumio volumio[11100]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:43:09 volumio volumio[11100]: info: Adding Signal Path Element [object Object] Sep 02 04:43:09 volumio volumio[11100]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:43:09 volumio volumio[11100]: info: FusionDsp - ---- installed callbackRead Sep 02 04:43:09 volumio volumio[11100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:09 volumio volumio[11100]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:43:09 volumio volumio[11100]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:43:09 volumio volumio[11100]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:43:09 volumio volumio[11100]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:43:09 volumio volumio[11100]: errno: -2, Sep 02 04:43:09 volumio volumio[11100]: code: 'ENOENT', Sep 02 04:43:09 volumio volumio[11100]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:43:09 volumio volumio[11100]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:43:09 volumio volumio[11100]: spawnargs: [ Sep 02 04:43:09 volumio volumio[11100]: '-p', Sep 02 04:43:09 volumio volumio[11100]: 9876, Sep 02 04:43:09 volumio volumio[11100]: '-o', Sep 02 04:43:09 volumio volumio[11100]: '/tmp/camilladsp.log', Sep 02 04:43:09 volumio volumio[11100]: '-l', Sep 02 04:43:09 volumio volumio[11100]: 'warn', Sep 02 04:43:09 volumio volumio[11100]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:43:09 volumio volumio[11100]: ] Sep 02 04:43:09 volumio volumio[11100]: } Sep 02 04:43:09 volumio volumio[11100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:10 volumio sudo[11330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:42 Sep 02 04:43:10 volumio sudo[11330]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:10 volumio sudo[11330]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:43:10 volumio sudo[11290]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:10 volumio sudo[11228]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:10 volumio sudo[11213]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:10 volumio mpd[11303]: Sep 02 04:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:43:10 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:43:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:43:10 volumio systemd[1]: Started dynamicswap service. Sep 02 04:43:10 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:43:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:43:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1485. Sep 02 04:43:10 volumio systemd[1]: Started dynamicswap service. Sep 02 04:43:10 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:43:10 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:43:10 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:43:11 volumio volumio-remote-updater[504]: [2025-09-02 04:43:11] [info] asio async_connect error: system:111 (Connection refused) Sep 02 04:43:11 volumio volumio-remote-updater[504]: [2025-09-02 04:43:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 04:43:11 volumio volumio-remote-updater[504]: [2025-09-02 04:43:11] [error] handle_connect error: Underlying Transport Error Sep 02 04:43:12 volumio volumio[11344]: info: ------------------------------------------- Sep 02 04:43:12 volumio volumio[11344]: info: ----- Volumio3 ---- Sep 02 04:43:12 volumio volumio[11344]: info: ------------------------------------------- Sep 02 04:43:12 volumio volumio[11344]: info: ----- System startup ---- Sep 02 04:43:12 volumio volumio[11344]: info: ------------------------------------------- Sep 02 04:43:13 volumio volumio[11344]: info: MYVOLUMIO Environment detected Sep 02 04:43:13 volumio volumio[11344]: info: Plugin folders cleanup Sep 02 04:43:13 volumio volumio[11344]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category audio_interface Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category miscellanea Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category music_service Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category plugins.json Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category system_controller Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category user_interface Sep 02 04:43:13 volumio volumio[11344]: info: Scanning into folder /data/plugins/ Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category audio_interface Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category music_service Sep 02 04:43:13 volumio volumio[11344]: info: Scanning category user_interface Sep 02 04:43:13 volumio volumio[11344]: info: Plugin folders cleanup completed Sep 02 04:43:13 volumio volumio[11344]: info: ------------------------------------------- Sep 02 04:43:13 volumio volumio[11344]: info: ----- Core plugins startup ---- Sep 02 04:43:13 volumio volumio[11344]: info: ------------------------------------------- Sep 02 04:43:13 volumio volumio[11344]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:43:13 volumio volumio[11344]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:43:13 volumio volumio[11344]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:43:13 volumio volumio[11344]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:43:13 volumio volumio[11344]: info: Loading plugins from folder /data/plugins/ Sep 02 04:43:13 volumio volumio[11344]: info: Loading plugin "system"... Sep 02 04:43:13 volumio volumio[11344]: info: Loading plugin "appearance"... Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "network"... Sep 02 04:43:14 volumio volumio[11344]: info: Refreshing Cached IP Addresses Sep 02 04:43:14 volumio sudo[11370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:43:14 volumio sudo[11370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:14 volumio sudo[11370]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "services"... Sep 02 04:43:14 volumio sudo[11372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:43:14 volumio sudo[11372]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "alsa_controller"... Sep 02 04:43:14 volumio sudo[11372]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:14 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "wizard"... Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "networkfs"... Sep 02 04:43:14 volumio volumio[11344]: info: Starting Udev Watcher for removable devices Sep 02 04:43:14 volumio volumio[11344]: info: Ignoring mount for partition: boot Sep 02 04:43:14 volumio volumio[11344]: info: Ignoring mount for partition: volumio Sep 02 04:43:14 volumio volumio[11344]: info: Ignoring mount for partition: volumio_data Sep 02 04:43:14 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "upnp"... Sep 02 04:43:14 volumio volumio[11344]: info: [1756780994788] Starting Upmpd Daemon Sep 02 04:43:14 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "my_music"... Sep 02 04:43:14 volumio volumio[11344]: info: Loading plugin "mpd"... Sep 02 04:43:15 volumio volumio[11344]: info: Plugin upnp_browser is not enabled Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "alarm-clock"... Sep 02 04:43:15 volumio volumio[11344]: info: Plugin airplay_emulation is not enabled Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "last_100"... Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "webradio"... Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "i2s_dacs"... Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "volumiodiscovery"... Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** For more information see Sep 02 04:43:15 volumio node[11344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:15 volumio volumio[11344]: *** WARNING *** For more information see Sep 02 04:43:15 volumio node[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:15 volumio node[11344]: *** WARNING *** For more information see Sep 02 04:43:15 volumio node[11344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:15 volumio node[11344]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:15 volumio node[11344]: *** WARNING *** For more information see Sep 02 04:43:15 volumio volumio[11344]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:43:15 volumio volumio[11344]: info: Discovery: Started advertising with name: Volumio Sep 02 04:43:15 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:15 volumio volumio[11344]: info: Loading plugin "jellyfin"... Sep 02 04:43:16 volumio volumio[11344]: info: Loading plugin "spop"... Sep 02 04:43:17 volumio volumio[11344]: info: Loading plugin "ytcr"... Sep 02 04:43:21 volumio volumio-remote-updater[504]: [2025-09-02 04:43:21] [connect] Successful connection Sep 02 04:43:21 volumio volumio[11344]: info: Loading plugin "outputs"... Sep 02 04:43:21 volumio volumio[11344]: info: Loading plugin "albumart"... Sep 02 04:43:21 volumio volumio[11344]: info: Plugin example_plugin is not enabled Sep 02 04:43:21 volumio volumio[11344]: info: Loading plugin "inputs"... Sep 02 04:43:21 volumio volumio[11344]: info: Loading plugin "updater_comm"... Sep 02 04:43:22 volumio volumio[11344]: info: Plugin mpdemulation is not enabled Sep 02 04:43:22 volumio volumio[11344]: info: Loading plugin "rest_api"... Sep 02 04:43:22 volumio volumio[11344]: info: Loading plugin "websocket"... Sep 02 04:43:22 volumio volumio[11344]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:43:22 volumio volumio[11344]: info: Loading plugin "fusiondsp"... Sep 02 04:43:22 volumio volumio[11344]: Forking 3 albumart workers Sep 02 04:43:22 volumio volumio[11344]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:43:22 volumio volumio[11344]: info: Loading plugin "Systeminfo"... Sep 02 04:43:22 volumio volumio[11344]: info: Loading i18n strings for locale hu Sep 02 04:43:22 volumio volumio[11344]: Updating browse sources language Sep 02 04:43:22 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::initPlayerControls Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: Express server listening on port 3000 Sep 02 04:43:23 volumio volumio[11344]: [Metrics] WebUI: 11s 42.45ms Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::resetVolumioState Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::getcurrentVolume Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:23 volumio volumio[11344]: Starting albumart workers Sep 02 04:43:23 volumio volumio[11344]: Starting albumart workers Sep 02 04:43:23 volumio volumio[11344]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:43:23 volumio volumio[11344]: Starting albumart workers Sep 02 04:43:23 volumio volumio-remote-updater[504]: [2025-09-02 04:43:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756781001 101 Sep 02 04:43:23 volumio volumio[11344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:23 volumio volumio[11344]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::pushState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::updateTrackBlock Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrackBlock Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::pushState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::setRandom true Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::pushState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:23 volumio volumio[11344]: info: Setting Device type: Raspberry PI Sep 02 04:43:23 volumio volumio[11344]: info: Listing playlists Sep 02 04:43:23 volumio volumio[11344]: info: Listing playlists Sep 02 04:43:23 volumio volumio[11344]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:43:23 volumio volumio[11344]: info: Discovery: Found device Volumio Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: Completed loading Core Plugins Sep 02 04:43:23 volumio volumio[11344]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:23 volumio volumio[11344]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:23 volumio volumio[11344]: info: Reading ALSA contributions from plugins. Sep 02 04:43:23 volumio volumio[11344]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:23 volumio volumio[11344]: info: CoreStateMachine::pushState Sep 02 04:43:23 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:23 volumio volumio[11344]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:23 volumio volumio[11344]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:23 volumio volumio[11344]: info: Output device has changed, restarting MPD Sep 02 04:43:24 volumio sudo[11478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:24 volumio sudo[11478]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio volumio[11344]: info: ___________ START PLUGINS ___________ Sep 02 04:43:24 volumio sudo[11478]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio volumio[11344]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:43:24 volumio volumio[11344]: info: Creating MPD Configuration file Sep 02 04:43:24 volumio sudo[11481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:24 volumio sudo[11481]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:24 volumio volumio[11344]: info: [1756781004099] CoreMusicLibrary::Adding element Last_100 Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:24 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:43:24 volumio sudo[11496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:24 volumio sudo[11496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:24 volumio volumio[11344]: info: [1756781004110] CoreMusicLibrary::Adding element Webradio Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:24 volumio sudo[11496]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:24 volumio volumio[11344]: info: Initializing BBC Radios Sep 02 04:43:24 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:24 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:24 volumio sudo[11510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:24 volumio sudo[11510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:43:24 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:24 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:24 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:43:24 volumio volumio[11344]: info: Discovery: Getting this device information Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:24 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:24 volumio volumio[11344]: info: [1756781004288] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:24 volumio volumio[11344]: Cannot find translation for source Jellyfin Sep 02 04:43:24 volumio volumio[11344]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:43:24 volumio volumio[11344]: info: Creating Spotify config file Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio sudo[11521]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:24 volumio sudo[11521]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio sudo[11521]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio volumio[11344]: info: Loading i18n strings for locale hu Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:43:24 volumio volumio[11344]: info: Volumio Calling Home Sep 02 04:43:24 volumio volumio[11344]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:24 volumio volumio[11344]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:24 volumio volumio[11344]: info: Reading ALSA contributions from plugins. Sep 02 04:43:24 volumio volumio[11344]: info: MPD Permissions set Sep 02 04:43:24 volumio volumio[11344]: info: MPD Permissions set Sep 02 04:43:24 volumio volumio[11344]: info: Spotify config file written Sep 02 04:43:24 volumio sudo[11545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:43:24 volumio sudo[11545]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio volumio[11344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 02 04:43:24 volumio volumio[11344]: info: Volumio called home Sep 02 04:43:24 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:43:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:24 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:43:24 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio sudo[11550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:43:24 volumio sudo[11550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio sudo[11551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:43:24 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:43:24 volumio sudo[11551]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:24 volumio sudo[11551]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio go-librespot[11549]: go-librespot daemon starting... Sep 02 04:43:24 volumio sudo[11545]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio sudo[11550]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:24 volumio go-librespot[11549]: time="2025-09-02T04:43:24+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:43:24 volumio go-librespot[11549]: time="2025-09-02T04:43:24+02:00" level=debug msg="app state loaded" Sep 02 04:43:24 volumio go-librespot[11549]: time="2025-09-02T04:43:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:24 volumio volumio[11344]: info: No need to fix Spotify hosts Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=info msg="zeroconf server listening on port 37399" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="obtained new client token: AAB6H36Zfeod4QqRdxjYlEZjb6Whqsf4B6RgD68nWfq8pjMtAybt3M/Jx9+3HR/DcfPCsRcwlrqZQrigdmdlpd/z3Cdajme/v28Q56ZwR8IBMNKR3S4UwgqhfSECOGjDWgcJacRXhr+xhtsTaidx4VpCU6ZBwgKdFK0f2bBRgUUHNeN1F/YvodjQn7aDSIOw48njC0fZwLmwu1G3yaCblHyUIeX/uNJHGj2DHEwxXty2PnjRSfoybD0c" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="completed keyexchange" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="completed challenge" Sep 02 04:43:25 volumio volumio[11344]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:25 volumio volumio[11344]: SPOTIFY: BQDeZtJXdCQ33_2wM-lZGSHhQsATlmuHXpaCMXW8y3EHmp6EN05TvTYwgiD47uNkn_j3OUXdZWSNUluwb_vyBpQxCnSeinlsVeob3UpZnHA_tDmM-uhvLkopuyDNMr9f5cgj0vDIO_jX__IN20mFLhIVFhxdYrx7SflupQnrA578F4LfNGWFXV-IKg4Y-9mTj1cBtMZoNbikIBq7GBslemokfcFVtHbeZN7dTInk5cW227q5FFLgj7IYQ4IoVFE07rmX4NFzrhgJUn-jDSkJtukxj5kVdPScsPhaocVR4liqcLE Sep 02 04:43:25 volumio volumio[11344]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:25 volumio volumio[11344]: info: New Spotify access token = BQDeZtJXdCQ33_2wM-lZGSHhQsATlmuHXpaCMXW8y3EHmp6EN05TvTYwgiD47uNkn_j3OUXdZWSNUluwb_vyBpQxCnSeinlsVeob3UpZnHA_tDmM-uhvLkopuyDNMr9f5cgj0vDIO_jX__IN20mFLhIVFhxdYrx7SflupQnrA578F4LfNGWFXV-IKg4Y-9mTj1cBtMZoNbikIBq7GBslemokfcFVtHbeZN7dTInk5cW227q5FFLgj7IYQ4IoVFE07rmX4NFzrhgJUn-jDSkJtukxj5kVdPScsPhaocVR4liqcLE Sep 02 04:43:25 volumio volumio[11344]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:25 volumio volumio[11344]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="dealer connection opened" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=trace msg="received accesspoint ping" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="received connection id: MmFhZDQyZWQtYTFl...OURCRDNBQThDQg==" Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:43:25 volumio volumio[11344]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:25 volumio volumio[11344]: info: Output device has changed, restarting MPD Sep 02 04:43:25 volumio sudo[11572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:25 volumio sudo[11572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:25 volumio go-librespot[11549]: time="2025-09-02T04:43:25+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:43:25 volumio sudo[11572]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:25 volumio sudo[11575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:25 volumio sudo[11575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:25 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:25 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:25 volumio volumio[11344]: info: MPD Permissions set Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio sudo[11579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:43:25 volumio sudo[11579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:25 volumio volumio[11344]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:43:25 volumio volumio[11344]: info: Spotify Successfully logged in Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:25 volumio volumio[11344]: info: [1756781005844] CoreMusicLibrary::Adding element Spotify Sep 02 04:43:25 volumio volumio[11344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:25 volumio volumio[11344]: Cannot find translation for source Jellyfin Sep 02 04:43:25 volumio volumio[11344]: Cannot find translation for source Spotify Sep 02 04:43:25 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:25 volumio sudo[11579]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:25 volumio volumio[11344]: info: Upmpdcli Daemon Started Sep 02 04:43:25 volumio sudo[11577]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:25 volumio sudo[11577]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:25 volumio sudo[11577]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:26 volumio volumio[11344]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:43:26 volumio volumio[11344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:26 volumio volumio[11344]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 02 04:43:26 volumio volumio[11344]: at dgram.js:337:20 Sep 02 04:43:26 volumio volumio[11344]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 02 04:43:26 volumio volumio[11344]: errno: -98, Sep 02 04:43:26 volumio volumio[11344]: code: 'EADDRINUSE', Sep 02 04:43:26 volumio volumio[11344]: syscall: 'bind', Sep 02 04:43:26 volumio volumio[11344]: address: '192.168.0.171', Sep 02 04:43:26 volumio volumio[11344]: port: 50386 Sep 02 04:43:26 volumio volumio[11344]: } Sep 02 04:43:26 volumio volumio[11344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:26 volumio sudo[11596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:42 Sep 02 04:43:26 volumio sudo[11596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:27 volumio sudo[11596]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:27 volumio volumio-remote-updater[504]: [2025-09-02 04:43:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 04:43:27 volumio volumio-remote-updater[504]: [2025-09-02 04:43:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 04:43:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 04:43:27 volumio sudo[11510]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:27 volumio sudo[11575]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:27 volumio sudo[11481]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 04:43:27 volumio systemd[1]: Started dynamicswap service. Sep 02 04:43:27 volumio mpd[11586]: Sep 02 04:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:43:27 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:43:27 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:43:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 04:43:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1486. Sep 02 04:43:27 volumio systemd[1]: Started dynamicswap service. Sep 02 04:43:27 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 04:43:27 volumio systemd[1]: Started Volumio Backend Module. Sep 02 04:43:27 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 04:43:29 volumio volumio[11612]: info: ------------------------------------------- Sep 02 04:43:29 volumio volumio[11612]: info: ----- Volumio3 ---- Sep 02 04:43:29 volumio volumio[11612]: info: ------------------------------------------- Sep 02 04:43:29 volumio volumio[11612]: info: ----- System startup ---- Sep 02 04:43:29 volumio volumio[11612]: info: ------------------------------------------- Sep 02 04:43:30 volumio volumio[11612]: info: MYVOLUMIO Environment detected Sep 02 04:43:30 volumio volumio[11612]: info: Plugin folders cleanup Sep 02 04:43:30 volumio volumio[11612]: info: Scanning into folder /volumio/app/plugins/ Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category audio_interface Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category miscellanea Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category music_service Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category plugins.json Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category system_controller Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category user_interface Sep 02 04:43:30 volumio volumio[11612]: info: Scanning into folder /data/plugins/ Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category audio_interface Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category music_service Sep 02 04:43:30 volumio volumio[11612]: info: Scanning category user_interface Sep 02 04:43:30 volumio volumio[11612]: info: Plugin folders cleanup completed Sep 02 04:43:30 volumio volumio[11612]: info: ------------------------------------------- Sep 02 04:43:30 volumio volumio[11612]: info: ----- Core plugins startup ---- Sep 02 04:43:30 volumio volumio[11612]: info: ------------------------------------------- Sep 02 04:43:30 volumio volumio[11612]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 04:43:30 volumio volumio[11612]: info: Adding plugin upnp to MyMusic Plugins Sep 02 04:43:30 volumio volumio[11612]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 04:43:30 volumio volumio[11612]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 04:43:30 volumio volumio[11612]: info: Loading plugins from folder /data/plugins/ Sep 02 04:43:30 volumio volumio[11612]: info: Loading plugin "system"... Sep 02 04:43:30 volumio volumio[11612]: info: Loading plugin "appearance"... Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "network"... Sep 02 04:43:31 volumio volumio[11612]: info: Refreshing Cached IP Addresses Sep 02 04:43:31 volumio sudo[11641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:43:31 volumio sudo[11641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:31 volumio sudo[11641]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:31 volumio sudo[11643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "services"... Sep 02 04:43:31 volumio sudo[11643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "alsa_controller"... Sep 02 04:43:31 volumio sudo[11643]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:31 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "wizard"... Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "networkfs"... Sep 02 04:43:31 volumio volumio[11612]: info: Starting Udev Watcher for removable devices Sep 02 04:43:31 volumio volumio[11612]: info: Ignoring mount for partition: boot Sep 02 04:43:31 volumio volumio[11612]: info: Ignoring mount for partition: volumio Sep 02 04:43:31 volumio volumio[11612]: info: Ignoring mount for partition: volumio_data Sep 02 04:43:31 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "volumio_command_line_client"... Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "upnp"... Sep 02 04:43:31 volumio volumio[11612]: info: [1756781011574] Starting Upmpd Daemon Sep 02 04:43:31 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "my_music"... Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "mpd"... Sep 02 04:43:31 volumio volumio[11612]: info: Plugin upnp_browser is not enabled Sep 02 04:43:31 volumio volumio[11612]: info: Loading plugin "alarm-clock"... Sep 02 04:43:32 volumio volumio[11612]: info: Plugin airplay_emulation is not enabled Sep 02 04:43:32 volumio volumio[11612]: info: Loading plugin "last_100"... Sep 02 04:43:32 volumio volumio[11612]: info: Loading plugin "webradio"... Sep 02 04:43:32 volumio volumio[11612]: info: Loading plugin "i2s_dacs"... Sep 02 04:43:32 volumio volumio[11612]: info: Loading plugin "volumiodiscovery"... Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** For more information see Sep 02 04:43:32 volumio node[11612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:32 volumio volumio[11612]: *** WARNING *** For more information see Sep 02 04:43:32 volumio node[11612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:32 volumio node[11612]: *** WARNING *** For more information see Sep 02 04:43:32 volumio node[11612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 04:43:32 volumio node[11612]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 04:43:32 volumio node[11612]: *** WARNING *** For more information see Sep 02 04:43:32 volumio volumio[11612]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 04:43:32 volumio volumio[11612]: info: Discovery: Started advertising with name: Volumio Sep 02 04:43:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:32 volumio volumio[11612]: info: Loading plugin "jellyfin"... Sep 02 04:43:32 volumio volumio-remote-updater[504]: [2025-09-02 04:43:32] [connect] Successful connection Sep 02 04:43:33 volumio volumio[11612]: info: Loading plugin "spop"... Sep 02 04:43:34 volumio volumio[11612]: info: Loading plugin "ytcr"... Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "outputs"... Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "albumart"... Sep 02 04:43:38 volumio volumio[11612]: info: Plugin example_plugin is not enabled Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "inputs"... Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "updater_comm"... Sep 02 04:43:38 volumio volumio[11612]: info: Plugin mpdemulation is not enabled Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "rest_api"... Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "websocket"... Sep 02 04:43:38 volumio volumio[11612]: info: Starting Socket.io Server version 2.3.0 Sep 02 04:43:38 volumio volumio[11612]: info: Loading plugin "fusiondsp"... Sep 02 04:43:38 volumio volumio[11612]: Forking 3 albumart workers Sep 02 04:43:39 volumio volumio[11612]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 04:43:39 volumio volumio[11612]: info: Loading plugin "Systeminfo"... Sep 02 04:43:39 volumio volumio[11612]: info: Loading i18n strings for locale hu Sep 02 04:43:39 volumio volumio[11612]: Updating browse sources language Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::initPlayerControls Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 04:43:39 volumio volumio[11612]: Express server listening on port 3000 Sep 02 04:43:39 volumio volumio[11612]: [Metrics] WebUI: 10s 947.54ms Sep 02 04:43:39 volumio volumio[11612]: info: CoreStateMachine::resetVolumioState Sep 02 04:43:39 volumio volumio[11612]: info: CoreStateMachine::getcurrentVolume Sep 02 04:43:39 volumio volumio[11612]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:39 volumio volumio[11612]: Starting albumart workers Sep 02 04:43:39 volumio volumio[11612]: Starting albumart workers Sep 02 04:43:39 volumio volumio[11612]: info: Volumio Network Manager: Network status updated: 1 Sep 02 04:43:40 volumio volumio[11612]: Starting albumart workers Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:40 volumio volumio[11612]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::pushState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::updateTrackBlock Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrackBlock Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::setRepeat false single undefined Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::pushState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::setRandom true Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::pushState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:40 volumio volumio[11612]: info: Setting Device type: Raspberry PI Sep 02 04:43:40 volumio volumio[11612]: info: Listing playlists Sep 02 04:43:40 volumio volumio[11612]: info: Listing playlists Sep 02 04:43:40 volumio volumio[11612]: info: Completed loading Core Plugins Sep 02 04:43:40 volumio volumio[11612]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:40 volumio volumio[11612]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 04:43:40 volumio volumio[11612]: info: Discovery: Found device Volumio Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:40 volumio volumio[11612]: info: Reading ALSA contributions from plugins. Sep 02 04:43:40 volumio volumio[11612]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:40 volumio volumio[11612]: info: CoreStateMachine::pushState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:40 volumio volumio[11612]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:40 volumio volumio[11612]: info: Output device has changed, restarting MPD Sep 02 04:43:40 volumio sudo[11726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:40 volumio sudo[11726]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:40 volumio sudo[11726]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:40 volumio volumio[11612]: info: ___________ START PLUGINS ___________ Sep 02 04:43:40 volumio volumio[11612]: info: ControllerMpd::onStart: Initializing MPD Sep 02 04:43:40 volumio volumio[11612]: info: Creating MPD Configuration file Sep 02 04:43:40 volumio sudo[11729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:40 volumio sudo[11729]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:40 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 04:43:40 volumio sudo[11740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:40 volumio sudo[11740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:40 volumio volumio[11612]: info: [1756781020837] CoreMusicLibrary::Adding element Last_100 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:40 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:40 volumio volumio[11612]: info: [1756781020844] CoreMusicLibrary::Adding element Webradio Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:40 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:40 volumio sudo[11740]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:40 volumio sudo[11742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:40 volumio sudo[11742]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:40 volumio volumio[11612]: info: Initializing BBC Radios Sep 02 04:43:40 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:40 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 04:43:40 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:40 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 04:43:40 volumio volumio[11612]: info: Discovery: Getting this device information Sep 02 04:43:40 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:40 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:40 volumio volumio[11612]: info: [1756781020992] CoreMusicLibrary::Adding element Jellyfin Sep 02 04:43:40 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:40 volumio volumio[11612]: Cannot find translation for source Jellyfin Sep 02 04:43:40 volumio volumio[11612]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 02 04:43:41 volumio volumio[11612]: info: Creating Spotify config file Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:41 volumio sudo[11756]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:41 volumio sudo[11756]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:41 volumio sudo[11756]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:41 volumio volumio[11612]: info: Loading i18n strings for locale hu Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:41 volumio volumio[11612]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 04:43:41 volumio volumio[11612]: info: Volumio Calling Home Sep 02 04:43:41 volumio volumio[11612]: info: Preparing to generate the ALSA configuration file Sep 02 04:43:41 volumio volumio[11612]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 04:43:41 volumio volumio[11612]: info: Reading ALSA contributions from plugins. Sep 02 04:43:41 volumio volumio[11612]: info: MPD Permissions set Sep 02 04:43:41 volumio volumio[11612]: info: MPD Permissions set Sep 02 04:43:41 volumio volumio[11612]: info: Volumio called home Sep 02 04:43:41 volumio volumio[11612]: info: Spotify config file written Sep 02 04:43:41 volumio sudo[11775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 04:43:41 volumio sudo[11775]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:41 volumio volumio[11612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 02 04:43:41 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 04:43:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:41 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:41 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:41 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:41 volumio volumio[11612]: info: No need to fix Spotify hosts Sep 02 04:43:41 volumio systemd[1]: Started go-librespot Daemon. Sep 02 04:43:41 volumio go-librespot[11780]: go-librespot daemon starting... Sep 02 04:43:41 volumio sudo[11775]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=debug msg="app state loaded" Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 04:43:41 volumio sudo[11796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 04:43:41 volumio sudo[11796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:41 volumio sudo[11796]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:41 volumio sudo[11801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 04:43:41 volumio sudo[11801]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:41 volumio sudo[11801]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=info msg="zeroconf server listening on port 43385" Sep 02 04:43:41 volumio volumio[11612]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:41 volumio volumio[11612]: SPOTIFY: BQCxsMnV0bxEVAXEOk5-yfoW8xboJcmXhFyVkMrc6RCsU43kyQ9lzXOD5iGmHTa0Be1ky9jTNhjcUOJONkPBgR3zP131TUrwTYyOrkobOJFb_cS9_PPwSa4xktq0-ntkD8L7FedU5NtxCwrdqruhqqt5onl1vHWo7BgbckHE_rgBDlwLLdxWqpEju2jQkXrnBwdEZzYFKDzmQzkyF1sL5Aif-NtzHyFwBgxaW3-RLbCg2dGaB5NTIe5D4jiojwHwKb8oCbycJOJgQK_tXYhcWdwVM1APYTKlQu_d9ronsIbwdA4 Sep 02 04:43:41 volumio volumio[11612]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 04:43:41 volumio volumio[11612]: info: New Spotify access token = BQCxsMnV0bxEVAXEOk5-yfoW8xboJcmXhFyVkMrc6RCsU43kyQ9lzXOD5iGmHTa0Be1ky9jTNhjcUOJONkPBgR3zP131TUrwTYyOrkobOJFb_cS9_PPwSa4xktq0-ntkD8L7FedU5NtxCwrdqruhqqt5onl1vHWo7BgbckHE_rgBDlwLLdxWqpEju2jQkXrnBwdEZzYFKDzmQzkyF1sL5Aif-NtzHyFwBgxaW3-RLbCg2dGaB5NTIe5D4jiojwHwKb8oCbycJOJgQK_tXYhcWdwVM1APYTKlQu_d9ronsIbwdA4 Sep 02 04:43:41 volumio volumio[11612]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 04:43:41 volumio go-librespot[11780]: time="2025-09-02T04:43:41+02:00" level=debug msg="obtained new client token: AACdJJi1PsD7GTu7UsoyIhKUOP864H0nNiJW1D1Iddeq4u1VnkjJgKojTUaXcDgVdfWi/gUjzraPbqusXH5yFc38/5TVYBk6Fo3ayGVqooA4TxR3xlq+38ekPPGOMd+lfvuX+Vc6Kj2EyYBVFETSz76Z3aTESu165Es7Q/dtpL+NTQg0coaibVw3YlLZRcwv4Zf7Dc+IzVXvcY3E7UNMC/9c7bCsvepORmJS3LXti0Xgl782G7hE2fTVl/g=" Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:42 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:42 volumio volumio[11612]: info: Asound.conf file unchanged, so no further update is needed Sep 02 04:43:42 volumio volumio[11612]: info: Output device has changed, restarting MPD Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 04:43:42 volumio sudo[11804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 04:43:42 volumio sudo[11804]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:42 volumio sudo[11804]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="completed keyexchange" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="completed challenge" Sep 02 04:43:42 volumio sudo[11807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 04:43:42 volumio sudo[11807]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 04:43:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 04:43:42 volumio systemd[1]: mpd.service: Succeeded. Sep 02 04:43:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 04:43:42 volumio volumio[11612]: info: MPD Permissions set Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio sudo[11812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 04:43:42 volumio sudo[11812]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:42 volumio systemd[1]: Starting Music Player Daemon... Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 04:43:42 volumio sudo[11812]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:42 volumio volumio[11612]: info: Upmpdcli Daemon Started Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="dealer connection opened" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=trace msg="starting dealer recv loop" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=trace msg="received accesspoint ping" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="received connection id: ZWY5ZGFlZmUtNzYz...RjU4NkUxQjExQQ==" Sep 02 04:43:42 volumio volumio[11612]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 02 04:43:42 volumio volumio[11612]: info: Spotify Successfully logged in Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 04:43:42 volumio volumio[11612]: info: [1756781022362] CoreMusicLibrary::Adding element Spotify Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 04:43:42 volumio volumio[11612]: Cannot find translation for source Jellyfin Sep 02 04:43:42 volumio volumio[11612]: Cannot find translation for source Spotify Sep 02 04:43:42 volumio sudo[11810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 04:43:42 volumio sudo[11810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:42 volumio sudo[11810]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 04:43:42 volumio go-librespot[11780]: time="2025-09-02T04:43:42+02:00" level=trace msg="received accesspoint pong ack" Sep 02 04:43:42 volumio volumio[11612]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 04:43:42 volumio volumio[11612]: info: VolumeController:: Volume=37 Mute =false Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Sep 02 04:43:42 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:42 volumio volumio[11612]: info: CoreStateMachine::pushState Sep 02 04:43:42 volumio volumio[11612]: info: CorePlayQueue::getTrack 0 Sep 02 04:43:42 volumio volumio[11612]: info: CoreCommandRouter::volumioPushState Sep 02 04:43:43 volumio volumio[11612]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:43:43 volumio volumio[11612]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 04:43:43 volumio volumio[11612]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 04:43:43 volumio volumio[11612]: info: camilladsp service started and running in background, instance 1 Sep 02 04:43:43 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 04:43:43 volumio volumio[11612]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:43:43 volumio volumio[11612]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 04:43:43 volumio volumio[11612]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 04:43:43 volumio volumio[11612]: info: FusionDsp loaded Sep 02 04:43:43 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 04:43:43 volumio sudo[11832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 04:43:43 volumio sudo[11832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:43 volumio sudo[11832]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:43 volumio volumio[11612]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 04:43:43 volumio volumio[11612]: info: Adding Signal Path Element [object Object] Sep 02 04:43:43 volumio volumio[11612]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 04:43:43 volumio volumio[11612]: info: FusionDsp - ---- installed callbackRead Sep 02 04:43:43 volumio volumio[11612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:43 volumio volumio[11612]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 04:43:43 volumio volumio[11612]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 04:43:43 volumio volumio[11612]: at onErrorNT (internal/child_process.js:465:16) Sep 02 04:43:43 volumio volumio[11612]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 04:43:43 volumio volumio[11612]: errno: -2, Sep 02 04:43:43 volumio volumio[11612]: code: 'ENOENT', Sep 02 04:43:43 volumio volumio[11612]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:43:43 volumio volumio[11612]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 04:43:43 volumio volumio[11612]: spawnargs: [ Sep 02 04:43:43 volumio volumio[11612]: '-p', Sep 02 04:43:43 volumio volumio[11612]: 9876, Sep 02 04:43:43 volumio volumio[11612]: '-o', Sep 02 04:43:43 volumio volumio[11612]: '/tmp/camilladsp.log', Sep 02 04:43:43 volumio volumio[11612]: '-l', Sep 02 04:43:43 volumio volumio[11612]: 'warn', Sep 02 04:43:43 volumio volumio[11612]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 04:43:43 volumio volumio[11612]: ] Sep 02 04:43:43 volumio volumio[11612]: } Sep 02 04:43:43 volumio volumio[11612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 04:43:44 volumio mpd[11819]: Sep 02 04:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 04:43:44 volumio systemd[1]: Started Music Player Daemon. Sep 02 04:43:44 volumio sudo[11843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 04:42 Sep 02 04:43:44 volumio sudo[11729]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:44 volumio sudo[11742]: pam_unix(sudo:session): session closed for user root Sep 02 04:43:44 volumio sudo[11843]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 04:43:44 volumio sudo[11807]: 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"