-- Logs begin at Thu 2025-09-04 19:37:11 CEST, end at Thu 2025-09-04 20:08:16 CEST. --
Sep 04 20:07:02 volumio volumio[23625]: info: Loading plugin "outputs"...
Sep 04 20:07:02 volumio volumio[23625]: info: Loading plugin "albumart"...
Sep 04 20:07:02 volumio volumio[23625]: info: Plugin example_plugin is not enabled
Sep 04 20:07:02 volumio volumio[23625]: info: Loading plugin "inputs"...
Sep 04 20:07:02 volumio volumio[23625]: info: Loading plugin "updater_comm"...
Sep 04 20:07:03 volumio volumio[23625]: info: Plugin mpdemulation is not enabled
Sep 04 20:07:03 volumio volumio[23625]: info: Loading plugin "rest_api"...
Sep 04 20:07:03 volumio volumio[23625]: info: Loading plugin "websocket"...
Sep 04 20:07:03 volumio volumio[23625]: info: Starting Socket.io Server version 2.3.0
Sep 04 20:07:03 volumio volumio[23625]: info: Loading plugin "fusiondsp"...
Sep 04 20:07:03 volumio volumio[23625]: Forking 3 albumart workers
Sep 04 20:07:03 volumio volumio[23625]: info: Applying required configuration parameters for plugin fusiondsp
Sep 04 20:07:03 volumio volumio[23625]: info: Loading plugin "Systeminfo"...
Sep 04 20:07:03 volumio volumio[23625]: info: Loading i18n strings for locale hu
Sep 04 20:07:03 volumio volumio[23625]: Updating browse sources language
Sep 04 20:07:03 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::initPlayerControls
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: Express server listening on port 3000
Sep 04 20:07:04 volumio volumio[23625]: [Metrics] WebUI: 11s 69.79ms
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::resetVolumioState
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::getcurrentVolume
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:04 volumio volumio[23625]: Starting albumart workers
Sep 04 20:07:04 volumio volumio[23625]: Starting albumart workers
Sep 04 20:07:04 volumio volumio[23625]: info: Volumio Network Manager: Network status updated: 1
Sep 04 20:07:04 volumio volumio[23625]: Starting albumart workers
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:04 volumio volumio[23625]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::pushState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::updateTrackBlock
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrackBlock
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::setRepeat false single undefined
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::pushState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::setRandom true
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::pushState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:04 volumio volumio[23625]: info: Setting Device type: Raspberry PI
Sep 04 20:07:04 volumio volumio[23625]: info: Listing playlists
Sep 04 20:07:04 volumio volumio[23625]: info: Listing playlists
Sep 04 20:07:04 volumio volumio[23625]: info: Completed loading Core Plugins
Sep 04 20:07:04 volumio volumio[23625]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:04 volumio volumio[23625]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227
Sep 04 20:07:04 volumio volumio[23625]: info: Discovery: Found device Volumio
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:04 volumio volumio[23625]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:04 volumio volumio[23625]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:04 volumio volumio[23625]: info: CoreStateMachine::pushState
Sep 04 20:07:04 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:04 volumio volumio[23625]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:04 volumio volumio[23625]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:04 volumio volumio[23625]: info: Output device has changed, restarting MPD
Sep 04 20:07:04 volumio volumio[23625]: info: ___________ START PLUGINS ___________
Sep 04 20:07:04 volumio sudo[23729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:04 volumio volumio[23625]: info: ControllerMpd::onStart: Initializing MPD
Sep 04 20:07:04 volumio volumio[23625]: info: Creating MPD Configuration file
Sep 04 20:07:04 volumio sudo[23729]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:04 volumio sudo[23729]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio sudo[23734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:05 volumio sudo[23734]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio sudo[23738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:05 volumio volumio[23625]: info: [1757009225055] CoreMusicLibrary::Adding element Last_100
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:05 volumio systemd[1]: Stopping Music Player Daemon...
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:05 volumio volumio[23625]: info: [1757009225060] CoreMusicLibrary::Adding element Webradio
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:05 volumio sudo[23738]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:05 volumio sudo[23744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:05 volumio sudo[23738]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio sudo[23744]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio volumio[23625]: info: Initializing BBC Radios
Sep 04 20:07:05 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:05 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:05 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 04 20:07:05 volumio volumio[23625]: info: Discovery: Getting this device information
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:05 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:05 volumio volumio[23625]: info: [1757009225242] CoreMusicLibrary::Adding element Jellyfin
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:05 volumio volumio[23625]: Cannot find translation for source Jellyfin
Sep 04 20:07:05 volumio volumio[23625]: 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 04 20:07:05 volumio volumio[23625]: info: Creating Spotify config file
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio sudo[23752]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:05 volumio sudo[23752]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio sudo[23752]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio volumio[23625]: info: Loading i18n strings for locale hu
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: FusionDsp - mixtype--------------------- Hardware
Sep 04 20:07:05 volumio volumio[23625]: info: Volumio Calling Home
Sep 04 20:07:05 volumio volumio[23625]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:05 volumio volumio[23625]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:05 volumio volumio[23625]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:05 volumio volumio[23625]: info: MPD Permissions set
Sep 04 20:07:05 volumio volumio[23625]: info: MPD Permissions set
Sep 04 20:07:05 volumio volumio[23625]: info: Spotify config file written
Sep 04 20:07:05 volumio sudo[23789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Sep 04 20:07:05 volumio sudo[23789]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio volumio[23625]: 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 04 20:07:05 volumio volumio[23625]: info: Volumio called home
Sep 04 20:07:05 volumio systemd[1]: Stopping go-librespot Daemon...
Sep 04 20:07:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:05 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Sep 04 20:07:05 volumio systemd[1]: Stopped go-librespot Daemon.
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio sudo[23793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio systemd[1]: Started go-librespot Daemon.
Sep 04 20:07:05 volumio sudo[23793]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio sudo[23796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:05 volumio go-librespot[23794]: go-librespot daemon starting...
Sep 04 20:07:05 volumio sudo[23796]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:05 volumio sudo[23793]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio sudo[23789]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio go-librespot[23794]: time="2025-09-04T20:07:05+02:00" level=info msg="running go-librespot 0.3.2"
Sep 04 20:07:05 volumio go-librespot[23794]: time="2025-09-04T20:07:05+02:00" level=debug msg="app state loaded"
Sep 04 20:07:05 volumio sudo[23796]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:05 volumio go-librespot[23794]: time="2025-09-04T20:07:05+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: No need to fix Spotify hosts
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:05 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=info msg="zeroconf server listening on port 44337"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="obtained new client token: AAAPlzQKrda3AR8681N57O5ug1SY1fKgJpiE4jwtmVEdkNLV/kquYLJtoIT76+9T/dSNxVwawgR2vAhJ/iIb501cvuLfKYL2BOZs303DjFtaH0hcbNhkVQxGdlN/VcEGDJmWjTxp+o8YfaNpP+PZmVdtQ2kUT/wKnvRw+JUishmdaEHlYIpy1XSXWyXVEi9jR6DYc785dA6GCEesJPvM3lF/xJB0OzcoESmPFWzj7ZoJTkkAyuN66v20g+Y="
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="completed keyexchange"
Sep 04 20:07:06 volumio volumio[23625]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:06 volumio volumio[23625]: SPOTIFY: BQCuepgkzSSFGTqDGXh_bTxA--QsLMt1r54aWhqJVVF-ygRHqrk0cBDncLOX08jmGJUfXQzgrQnKeHpfkAaRq_YVA-ub3wwMEiy9lf3Xy0qHJxAaJ1yYJ2N4FqtNvr6kwKgmHQHb24U5jtl7xgvjHoWDNdvyFAOhshuG6NRemkGje6y8ZsDF0aKjdYrg_tV8Sb1ukgdVHGln4DnTntCEDW8NVIWmKPR2I7OaaqGK-cVHM8W8Vxp5sfbciWA0PCduEWfy1djZ7i1mXrQ_uAGvjHgbFLCdAtf2YdLIkUmj9ojeGSg
Sep 04 20:07:06 volumio volumio[23625]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="completed challenge"
Sep 04 20:07:06 volumio volumio[23625]: info: New Spotify access token = BQCuepgkzSSFGTqDGXh_bTxA--QsLMt1r54aWhqJVVF-ygRHqrk0cBDncLOX08jmGJUfXQzgrQnKeHpfkAaRq_YVA-ub3wwMEiy9lf3Xy0qHJxAaJ1yYJ2N4FqtNvr6kwKgmHQHb24U5jtl7xgvjHoWDNdvyFAOhshuG6NRemkGje6y8ZsDF0aKjdYrg_tV8Sb1ukgdVHGln4DnTntCEDW8NVIWmKPR2I7OaaqGK-cVHM8W8Vxp5sfbciWA0PCduEWfy1djZ7i1mXrQ_uAGvjHgbFLCdAtf2YdLIkUmj9ojeGSg
Sep 04 20:07:06 volumio volumio[23625]: info: Spotify credentials grant success - running version from March 24, 2019
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=info msg="authenticated AP" username="lo*********************vv"
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:06 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:06 volumio volumio[23625]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:06 volumio volumio[23625]: info: Output device has changed, restarting MPD
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=info msg="authenticated Login5" username="lo*********************vv"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv"
Sep 04 20:07:06 volumio sudo[23816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:06 volumio sudo[23816]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:06 volumio sudo[23816]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:06 volumio sudo[23818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:06 volumio sudo[23818]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="dealer connection opened"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=trace msg="starting accesspoint recv loop"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=trace msg="starting dealer recv loop"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=trace msg="received accesspoint ping"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Sep 04 20:07:06 volumio sudo[23822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Sep 04 20:07:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:06 volumio sudo[23822]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:06 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="received connection id: ZWVkNWU1ZWItNDVm...MTA4NTM1QjRDNA=="
Sep 04 20:07:06 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=trace msg="received accesspoint pong ack"
Sep 04 20:07:06 volumio volumio[23625]: info: MPD Permissions set
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:06 volumio sudo[23822]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:06 volumio volumio[23625]: info: Upmpdcli Daemon Started
Sep 04 20:07:06 volumio volumio[23625]: 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 04 20:07:06 volumio volumio[23625]: info: Spotify Successfully logged in
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:06 volumio volumio[23625]: info: [1757009226781] CoreMusicLibrary::Adding element Spotify
Sep 04 20:07:06 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:06 volumio volumio[23625]: Cannot find translation for source Jellyfin
Sep 04 20:07:06 volumio volumio[23625]: Cannot find translation for source Spotify
Sep 04 20:07:06 volumio go-librespot[23794]: time="2025-09-04T20:07:06+02:00" level=debug msg="put connect state because NEW_DEVICE"
Sep 04 20:07:06 volumio sudo[23825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:06 volumio sudo[23825]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:06 volumio sudo[23825]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:07 volumio volumio[23625]: info: [yt-cast-receiver] DIAL server listening on port 8098
Sep 04 20:07:07 volumio volumio[23625]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:07 volumio volumio[23625]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:07 volumio volumio[23625]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:07 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:07 volumio volumio[23625]: info: CoreStateMachine::pushState
Sep 04 20:07:07 volumio volumio[23625]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:07 volumio volumio[23625]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:07 volumio volumio[23625]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:07 volumio volumio[23625]: 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 04 20:07:07 volumio volumio[23625]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:07 volumio volumio[23625]: info: camilladsp service started and running in background, instance 1
Sep 04 20:07:07 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:07 volumio volumio[23625]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:07 volumio volumio[23625]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Sep 04 20:07:07 volumio volumio[23625]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:07 volumio volumio[23625]: info: FusionDsp loaded
Sep 04 20:07:07 volumio volumio[23625]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:07 volumio sudo[23845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Sep 04 20:07:07 volumio sudo[23845]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:07 volumio volumio[23625]: info: FusionDsp - Reporting Fusion DSP Enabled
Sep 04 20:07:07 volumio volumio[23625]: info: Adding Signal Path Element [object Object]
Sep 04 20:07:07 volumio volumio[23625]: info: Adding fusiondspeq DSP Signal Path Element
Sep 04 20:07:07 volumio sudo[23845]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:07 volumio volumio[23625]: info: FusionDsp - ---- installed callbackRead
Sep 04 20:07:07 volumio volumio[23625]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:07 volumio volumio[23625]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Sep 04 20:07:07 volumio volumio[23625]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Sep 04 20:07:07 volumio volumio[23625]: at onErrorNT (internal/child_process.js:465:16)
Sep 04 20:07:07 volumio volumio[23625]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Sep 04 20:07:07 volumio volumio[23625]: at runNextTicks (internal/process/task_queues.js:62:3)
Sep 04 20:07:07 volumio volumio[23625]: at listOnTimeout (internal/timers.js:523:9)
Sep 04 20:07:07 volumio volumio[23625]: at processTimers (internal/timers.js:497:7) {
Sep 04 20:07:07 volumio volumio[23625]: errno: -2,
Sep 04 20:07:07 volumio volumio[23625]: code: 'ENOENT',
Sep 04 20:07:07 volumio volumio[23625]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:07 volumio volumio[23625]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:07 volumio volumio[23625]: spawnargs: [
Sep 04 20:07:07 volumio volumio[23625]: '-p',
Sep 04 20:07:07 volumio volumio[23625]: 9876,
Sep 04 20:07:07 volumio volumio[23625]: '-o',
Sep 04 20:07:07 volumio volumio[23625]: '/tmp/camilladsp.log',
Sep 04 20:07:07 volumio volumio[23625]: '-l',
Sep 04 20:07:07 volumio volumio[23625]: 'warn',
Sep 04 20:07:07 volumio volumio[23625]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Sep 04 20:07:07 volumio volumio[23625]: ]
Sep 04 20:07:07 volumio volumio[23625]: }
Sep 04 20:07:07 volumio volumio[23625]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:08 volumio sudo[23856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 20:06
Sep 04 20:07:08 volumio sudo[23856]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:08 volumio mpd[23832]: Sep 04 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 04 20:07:08 volumio sudo[23856]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:08 volumio systemd[1]: Started Music Player Daemon.
Sep 04 20:07:08 volumio sudo[23744]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:08 volumio sudo[23818]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:08 volumio sudo[23734]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Sep 04 20:07:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Sep 04 20:07:08 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:08 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Sep 04 20:07:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14773.
Sep 04 20:07:08 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:08 volumio systemd[1]: Stopped Volumio Backend Module.
Sep 04 20:07:08 volumio systemd[1]: Started Volumio Backend Module.
Sep 04 20:07:08 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:09 volumio volumio-remote-updater[504]: [2025-09-04 20:07:09] [info] asio async_connect error: system:111 (Connection refused)
Sep 04 20:07:09 volumio volumio-remote-updater[504]: [2025-09-04 20:07:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Sep 04 20:07:09 volumio volumio-remote-updater[504]: [2025-09-04 20:07:09] [error] handle_connect error: Underlying Transport Error
Sep 04 20:07:10 volumio volumio[23869]: info: -------------------------------------------
Sep 04 20:07:10 volumio volumio[23869]: info: ----- Volumio3 ----
Sep 04 20:07:10 volumio volumio[23869]: info: -------------------------------------------
Sep 04 20:07:10 volumio volumio[23869]: info: ----- System startup ----
Sep 04 20:07:10 volumio volumio[23869]: info: -------------------------------------------
Sep 04 20:07:11 volumio volumio[23869]: info: MYVOLUMIO Environment detected
Sep 04 20:07:11 volumio volumio[23869]: info: Plugin folders cleanup
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning into folder /volumio/app/plugins/
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category audio_interface
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category miscellanea
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category music_service
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category plugins.json
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category system_controller
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category user_interface
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning into folder /data/plugins/
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category audio_interface
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category music_service
Sep 04 20:07:11 volumio volumio[23869]: info: Scanning category user_interface
Sep 04 20:07:11 volumio volumio[23869]: info: Plugin folders cleanup completed
Sep 04 20:07:11 volumio volumio[23869]: info: -------------------------------------------
Sep 04 20:07:11 volumio volumio[23869]: info: ----- Core plugins startup ----
Sep 04 20:07:11 volumio volumio[23869]: info: -------------------------------------------
Sep 04 20:07:11 volumio volumio[23869]: info: Loading plugins from folder /volumio/app/plugins/
Sep 04 20:07:11 volumio volumio[23869]: info: Adding plugin upnp to MyMusic Plugins
Sep 04 20:07:11 volumio volumio[23869]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 04 20:07:11 volumio volumio[23869]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 04 20:07:11 volumio volumio[23869]: info: Loading plugins from folder /data/plugins/
Sep 04 20:07:11 volumio volumio[23869]: info: Loading plugin "system"...
Sep 04 20:07:11 volumio volumio[23869]: info: Loading plugin "appearance"...
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "network"...
Sep 04 20:07:12 volumio volumio[23869]: info: Refreshing Cached IP Addresses
Sep 04 20:07:12 volumio sudo[23933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:12 volumio sudo[23933]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:12 volumio sudo[23933]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "services"...
Sep 04 20:07:12 volumio sudo[23935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:12 volumio sudo[23935]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "alsa_controller"...
Sep 04 20:07:12 volumio sudo[23935]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:12 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "wizard"...
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "networkfs"...
Sep 04 20:07:12 volumio volumio[23869]: info: Starting Udev Watcher for removable devices
Sep 04 20:07:12 volumio volumio[23869]: info: Ignoring mount for partition: boot
Sep 04 20:07:12 volumio volumio[23869]: info: Ignoring mount for partition: volumio
Sep 04 20:07:12 volumio volumio[23869]: info: Ignoring mount for partition: volumio_data
Sep 04 20:07:12 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "volumio_command_line_client"...
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "upnp"...
Sep 04 20:07:12 volumio volumio[23869]: info: [1757009232588] Starting Upmpd Daemon
Sep 04 20:07:12 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "my_music"...
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "mpd"...
Sep 04 20:07:12 volumio volumio[23869]: info: Plugin upnp_browser is not enabled
Sep 04 20:07:12 volumio volumio[23869]: info: Loading plugin "alarm-clock"...
Sep 04 20:07:13 volumio volumio[23869]: info: Plugin airplay_emulation is not enabled
Sep 04 20:07:13 volumio volumio[23869]: info: Loading plugin "last_100"...
Sep 04 20:07:13 volumio volumio[23869]: info: Loading plugin "webradio"...
Sep 04 20:07:13 volumio volumio[23869]: info: Loading plugin "i2s_dacs"...
Sep 04 20:07:13 volumio volumio[23869]: info: Loading plugin "volumiodiscovery"...
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** For more information see
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:13 volumio volumio[23869]: *** WARNING *** For more information see
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** For more information see
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:13 volumio node[23869]: *** WARNING *** For more information see
Sep 04 20:07:13 volumio volumio[23869]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 04 20:07:13 volumio volumio[23869]: info: Discovery: Started advertising with name: Volumio
Sep 04 20:07:13 volumio nmbd[721]: [2025/09/04 20:07:13.299641, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Sep 04 20:07:13 volumio nmbd[721]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>.
Sep 04 20:07:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185.
Sep 04 20:07:13 volumio nmbd[721]: [2025/09/04 20:07:13.301847, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Sep 04 20:07:13 volumio nmbd[721]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>.
Sep 04 20:07:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185.
Sep 04 20:07:13 volumio nmbd[721]: [2025/09/04 20:07:13.302227, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Sep 04 20:07:13 volumio nmbd[721]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>.
Sep 04 20:07:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185.
Sep 04 20:07:13 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:13 volumio volumio[23869]: info: Loading plugin "jellyfin"...
Sep 04 20:07:14 volumio volumio[23869]: info: Loading plugin "spop"...
Sep 04 20:07:15 volumio volumio[23869]: info: Loading plugin "ytcr"...
Sep 04 20:07:19 volumio volumio-remote-updater[504]: [2025-09-04 20:07:19] [connect] Successful connection
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "outputs"...
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "albumart"...
Sep 04 20:07:19 volumio volumio[23869]: info: Plugin example_plugin is not enabled
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "inputs"...
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "updater_comm"...
Sep 04 20:07:19 volumio volumio[23869]: info: Plugin mpdemulation is not enabled
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "rest_api"...
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "websocket"...
Sep 04 20:07:19 volumio volumio[23869]: info: Starting Socket.io Server version 2.3.0
Sep 04 20:07:19 volumio volumio[23869]: info: Loading plugin "fusiondsp"...
Sep 04 20:07:20 volumio volumio[23869]: Forking 3 albumart workers
Sep 04 20:07:20 volumio volumio[23869]: info: Applying required configuration parameters for plugin fusiondsp
Sep 04 20:07:20 volumio volumio[23869]: info: Loading plugin "Systeminfo"...
Sep 04 20:07:20 volumio volumio[23869]: info: Loading i18n strings for locale hu
Sep 04 20:07:20 volumio volumio[23869]: Updating browse sources language
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::initPlayerControls
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:20 volumio volumio[23869]: Express server listening on port 3000
Sep 04 20:07:20 volumio volumio[23869]: [Metrics] WebUI: 11s 106.63ms
Sep 04 20:07:20 volumio volumio[23869]: info: CoreStateMachine::resetVolumioState
Sep 04 20:07:20 volumio volumio[23869]: info: CoreStateMachine::getcurrentVolume
Sep 04 20:07:20 volumio volumio[23869]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:21 volumio volumio[23869]: Starting albumart workers
Sep 04 20:07:21 volumio volumio[23869]: Starting albumart workers
Sep 04 20:07:21 volumio volumio[23869]: info: Volumio Network Manager: Network status updated: 1
Sep 04 20:07:21 volumio volumio[23869]: Starting albumart workers
Sep 04 20:07:21 volumio volumio-remote-updater[504]: [2025-09-04 20:07:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757009239 101
Sep 04 20:07:21 volumio volumio[23869]: 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 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:21 volumio volumio[23869]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::pushState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::updateTrackBlock
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrackBlock
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::setRepeat false single undefined
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::pushState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::setRandom true
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::pushState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:21 volumio volumio[23869]: info: Setting Device type: Raspberry PI
Sep 04 20:07:21 volumio volumio[23869]: info: Listing playlists
Sep 04 20:07:21 volumio volumio[23869]: info: Listing playlists
Sep 04 20:07:21 volumio volumio[23869]: info: Completed loading Core Plugins
Sep 04 20:07:21 volumio volumio[23869]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:21 volumio volumio[23869]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227
Sep 04 20:07:21 volumio volumio[23869]: info: Discovery: Found device Volumio
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:21 volumio volumio[23869]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:21 volumio volumio[23869]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:21 volumio volumio[23869]: info: CoreStateMachine::pushState
Sep 04 20:07:21 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:21 volumio volumio[23869]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:21 volumio volumio[23869]: info: Output device has changed, restarting MPD
Sep 04 20:07:21 volumio sudo[24021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:21 volumio sudo[24021]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:21 volumio sudo[24021]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:21 volumio volumio[23869]: info: ___________ START PLUGINS ___________
Sep 04 20:07:21 volumio volumio[23869]: info: ControllerMpd::onStart: Initializing MPD
Sep 04 20:07:21 volumio volumio[23869]: info: Creating MPD Configuration file
Sep 04 20:07:21 volumio sudo[24024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:21 volumio sudo[24024]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:21 volumio sudo[24035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:21 volumio sudo[24035]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:21 volumio sudo[24035]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:21 volumio systemd[1]: Stopping Music Player Daemon...
Sep 04 20:07:21 volumio volumio[23869]: info: [1757009241942] CoreMusicLibrary::Adding element Last_100
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:21 volumio sudo[24038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:21 volumio sudo[24038]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:21 volumio volumio[23869]: info: [1757009241972] CoreMusicLibrary::Adding element Webradio
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:21 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:21 volumio volumio[23869]: info: Initializing BBC Radios
Sep 04 20:07:21 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:21 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:22 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:22 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Sep 04 20:07:22 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:22 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:22 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 04 20:07:22 volumio volumio[23869]: info: Discovery: Getting this device information
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:22 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:22 volumio volumio[23869]: info: [1757009242100] CoreMusicLibrary::Adding element Jellyfin
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:22 volumio volumio[23869]: Cannot find translation for source Jellyfin
Sep 04 20:07:22 volumio volumio[23869]: 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 04 20:07:22 volumio volumio[23869]: info: Creating Spotify config file
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:22 volumio sudo[24049]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:22 volumio sudo[24049]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:22 volumio sudo[24049]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:22 volumio volumio[23869]: info: Loading i18n strings for locale hu
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:22 volumio volumio[23869]: info: FusionDsp - mixtype--------------------- Hardware
Sep 04 20:07:22 volumio volumio[23869]: info: Volumio Calling Home
Sep 04 20:07:22 volumio volumio[23869]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:22 volumio volumio[23869]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:22 volumio volumio[23869]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:22 volumio volumio[23869]: info: MPD Permissions set
Sep 04 20:07:22 volumio volumio[23869]: info: MPD Permissions set
Sep 04 20:07:22 volumio sudo[24072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:22 volumio sudo[24072]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:22 volumio sudo[24072]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:22 volumio volumio[23869]: info: Volumio called home
Sep 04 20:07:22 volumio sudo[24075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:22 volumio volumio[23869]: info: Spotify config file written
Sep 04 20:07:22 volumio sudo[24075]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:22 volumio sudo[24075]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:22 volumio sudo[24078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Sep 04 20:07:22 volumio sudo[24078]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:22 volumio systemd[1]: Stopping go-librespot Daemon...
Sep 04 20:07:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:22 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Sep 04 20:07:22 volumio systemd[1]: Stopped go-librespot Daemon.
Sep 04 20:07:22 volumio systemd[1]: Started go-librespot Daemon.
Sep 04 20:07:22 volumio go-librespot[24080]: go-librespot daemon starting...
Sep 04 20:07:22 volumio sudo[24078]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:22 volumio go-librespot[24080]: time="2025-09-04T20:07:22+02:00" level=info msg="running go-librespot 0.3.2"
Sep 04 20:07:22 volumio go-librespot[24080]: time="2025-09-04T20:07:22+02:00" level=debug msg="app state loaded"
Sep 04 20:07:22 volumio go-librespot[24080]: time="2025-09-04T20:07:22+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Sep 04 20:07:22 volumio volumio[23869]: 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 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:22 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: No need to fix Spotify hosts
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=info msg="zeroconf server listening on port 36817"
Sep 04 20:07:23 volumio volumio[23869]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:23 volumio volumio[23869]: SPOTIFY: BQDVJ_g6v5c2h7FFp1AW2z6PMGLEee-YmXSTJWuqqhsQpBFl7J4hK5ZT69E8S8zNKM646LsjtgTTXomXXIW1rYKl1b8xMsq_71yBXFr48QupRJE9H3Z67Ko2QJTJoY8tqlLdzX2YhsZ4rn_CWCYtRK7t_qStYTHVE7brCWpvqRD3c-3IZqB3ijOXQ6l1zHM_UzwSE8d_w5A7U0vKZJXi_gg8Va9DIl_kqLeKOLG4k5Xx_cbt4hWbRUGkUTrzDnBekiH6Yo5XAo5vfFICE8CkFogQ23whOuYdEPQSGPn1PrtBAUs
Sep 04 20:07:23 volumio volumio[23869]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:23 volumio volumio[23869]: info: New Spotify access token = BQDVJ_g6v5c2h7FFp1AW2z6PMGLEee-YmXSTJWuqqhsQpBFl7J4hK5ZT69E8S8zNKM646LsjtgTTXomXXIW1rYKl1b8xMsq_71yBXFr48QupRJE9H3Z67Ko2QJTJoY8tqlLdzX2YhsZ4rn_CWCYtRK7t_qStYTHVE7brCWpvqRD3c-3IZqB3ijOXQ6l1zHM_UzwSE8d_w5A7U0vKZJXi_gg8Va9DIl_kqLeKOLG4k5Xx_cbt4hWbRUGkUTrzDnBekiH6Yo5XAo5vfFICE8CkFogQ23whOuYdEPQSGPn1PrtBAUs
Sep 04 20:07:23 volumio volumio[23869]: info: Spotify credentials grant success - running version from March 24, 2019
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="obtained new client token: AADTE00lMy3kF0DjLIpWjM7YW+dbcEZWdVcdrYTUoG6nIxDoUgwOvV6mYPGSq0cSc/Igm0pXTCrjHi2jNR5Q+s8WiUN0gxUwFUXmkL0gK752iM86IY6p/HpcL6olMwfSQ6j1FMP/GU9ULCA0Z7NSv7sDsgnWzJD6+Myg/qLVfuQI/ZCTb0zdU8VLhYumQK/Ed79zFF5zT3tkkVUyttgvQ3wyMX2Cx4XiPt/lUuOSF9xAprplOS5RH0vYjAs="
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:23 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Sep 04 20:07:23 volumio sudo[24097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 04 20:07:23 volumio sudo[24097]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="completed keyexchange"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="completed challenge"
Sep 04 20:07:23 volumio volumio[23869]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:23 volumio volumio[23869]: info: Output device has changed, restarting MPD
Sep 04 20:07:23 volumio sudo[24097]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=info msg="authenticated AP" username="lo*********************vv"
Sep 04 20:07:23 volumio sudo[24100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:23 volumio sudo[24100]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:23 volumio sudo[24100]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:23 volumio sudo[24102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:23 volumio sudo[24102]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:23 volumio volumio[23869]: info: Upmpdcli Daemon Started
Sep 04 20:07:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:23 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:23 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:23 volumio volumio[23869]: info: MPD Permissions set
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=info msg="authenticated Login5" username="lo*********************vv"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv"
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="dealer connection opened"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=trace msg="starting accesspoint recv loop"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=trace msg="starting dealer recv loop"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=trace msg="received accesspoint ping"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="received connection id: NjIyN2I5OTUtZWJk...REZGMjE2OUZCMg=="
Sep 04 20:07:23 volumio volumio[23869]: 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 04 20:07:23 volumio volumio[23869]: info: Spotify Successfully logged in
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:23 volumio volumio[23869]: info: [1757009243703] CoreMusicLibrary::Adding element Spotify
Sep 04 20:07:23 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:23 volumio volumio[23869]: Cannot find translation for source Jellyfin
Sep 04 20:07:23 volumio volumio[23869]: Cannot find translation for source Spotify
Sep 04 20:07:23 volumio sudo[24106]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:23 volumio sudo[24106]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="put connect state because NEW_DEVICE"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Sep 04 20:07:23 volumio go-librespot[24080]: time="2025-09-04T20:07:23+02:00" level=trace msg="received accesspoint pong ack"
Sep 04 20:07:23 volumio sudo[24106]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:24 volumio volumio[23869]: info: [yt-cast-receiver] DIAL server listening on port 8098
Sep 04 20:07:24 volumio volumio[23869]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:24 volumio volumio[23869]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:24 volumio volumio[23869]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:24 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:24 volumio volumio[23869]: info: CoreStateMachine::pushState
Sep 04 20:07:24 volumio volumio[23869]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:24 volumio volumio[23869]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:24 volumio volumio[23869]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:24 volumio volumio[23869]: 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 04 20:07:24 volumio volumio[23869]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:24 volumio volumio[23869]: info: camilladsp service started and running in background, instance 1
Sep 04 20:07:24 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:24 volumio volumio[23869]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:24 volumio volumio[23869]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Sep 04 20:07:24 volumio volumio[23869]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:24 volumio volumio[23869]: info: FusionDsp loaded
Sep 04 20:07:24 volumio volumio[23869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:24 volumio sudo[24125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Sep 04 20:07:24 volumio sudo[24125]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:24 volumio sudo[24125]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:24 volumio volumio[23869]: info: FusionDsp - Reporting Fusion DSP Enabled
Sep 04 20:07:24 volumio volumio[23869]: info: Adding Signal Path Element [object Object]
Sep 04 20:07:24 volumio volumio[23869]: info: Adding fusiondspeq DSP Signal Path Element
Sep 04 20:07:24 volumio volumio[23869]: info: FusionDsp - ---- installed callbackRead
Sep 04 20:07:24 volumio volumio[23869]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:24 volumio volumio[23869]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Sep 04 20:07:24 volumio volumio[23869]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Sep 04 20:07:24 volumio volumio[23869]: at onErrorNT (internal/child_process.js:465:16)
Sep 04 20:07:24 volumio volumio[23869]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Sep 04 20:07:24 volumio volumio[23869]: at runNextTicks (internal/process/task_queues.js:62:3)
Sep 04 20:07:24 volumio volumio[23869]: at listOnTimeout (internal/timers.js:523:9)
Sep 04 20:07:24 volumio volumio[23869]: at processTimers (internal/timers.js:497:7) {
Sep 04 20:07:24 volumio volumio[23869]: errno: -2,
Sep 04 20:07:24 volumio volumio[23869]: code: 'ENOENT',
Sep 04 20:07:24 volumio volumio[23869]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:24 volumio volumio[23869]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:24 volumio volumio[23869]: spawnargs: [
Sep 04 20:07:24 volumio volumio[23869]: '-p',
Sep 04 20:07:24 volumio volumio[23869]: 9876,
Sep 04 20:07:24 volumio volumio[23869]: '-o',
Sep 04 20:07:24 volumio volumio[23869]: '/tmp/camilladsp.log',
Sep 04 20:07:24 volumio volumio[23869]: '-l',
Sep 04 20:07:24 volumio volumio[23869]: 'warn',
Sep 04 20:07:24 volumio volumio[23869]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Sep 04 20:07:24 volumio volumio[23869]: ]
Sep 04 20:07:24 volumio volumio[23869]: }
Sep 04 20:07:24 volumio volumio[23869]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:25 volumio sudo[24136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 20:06
Sep 04 20:07:25 volumio sudo[24136]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:25 volumio mpd[24112]: Sep 04 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 04 20:07:25 volumio systemd[1]: Started Music Player Daemon.
Sep 04 20:07:25 volumio sudo[24024]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:25 volumio sudo[24102]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:25 volumio sudo[24038]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:25 volumio sudo[24136]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:25 volumio volumio-remote-updater[504]: [2025-09-04 20:07:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Sep 04 20:07:25 volumio volumio-remote-updater[504]: [2025-09-04 20:07:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Sep 04 20:07:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Sep 04 20:07:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Sep 04 20:07:25 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:25 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Sep 04 20:07:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14774.
Sep 04 20:07:25 volumio systemd[1]: Stopped Volumio Backend Module.
Sep 04 20:07:25 volumio systemd[1]: Started Volumio Backend Module.
Sep 04 20:07:25 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:25 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:27 volumio volumio[24148]: info: -------------------------------------------
Sep 04 20:07:27 volumio volumio[24148]: info: ----- Volumio3 ----
Sep 04 20:07:27 volumio volumio[24148]: info: -------------------------------------------
Sep 04 20:07:27 volumio volumio[24148]: info: ----- System startup ----
Sep 04 20:07:27 volumio volumio[24148]: info: -------------------------------------------
Sep 04 20:07:28 volumio volumio[24148]: info: MYVOLUMIO Environment detected
Sep 04 20:07:28 volumio volumio[24148]: info: Plugin folders cleanup
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning into folder /volumio/app/plugins/
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category audio_interface
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category miscellanea
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category music_service
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category plugins.json
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category system_controller
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category user_interface
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning into folder /data/plugins/
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category audio_interface
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category music_service
Sep 04 20:07:28 volumio volumio[24148]: info: Scanning category user_interface
Sep 04 20:07:28 volumio volumio[24148]: info: Plugin folders cleanup completed
Sep 04 20:07:28 volumio volumio[24148]: info: -------------------------------------------
Sep 04 20:07:28 volumio volumio[24148]: info: ----- Core plugins startup ----
Sep 04 20:07:28 volumio volumio[24148]: info: -------------------------------------------
Sep 04 20:07:28 volumio volumio[24148]: info: Loading plugins from folder /volumio/app/plugins/
Sep 04 20:07:28 volumio volumio[24148]: info: Adding plugin upnp to MyMusic Plugins
Sep 04 20:07:28 volumio volumio[24148]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 04 20:07:28 volumio volumio[24148]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 04 20:07:28 volumio volumio[24148]: info: Loading plugins from folder /data/plugins/
Sep 04 20:07:28 volumio volumio[24148]: info: Loading plugin "system"...
Sep 04 20:07:28 volumio volumio[24148]: info: Loading plugin "appearance"...
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "network"...
Sep 04 20:07:29 volumio volumio[24148]: info: Refreshing Cached IP Addresses
Sep 04 20:07:29 volumio sudo[24175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:29 volumio sudo[24175]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:29 volumio sudo[24175]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:29 volumio sudo[24177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "services"...
Sep 04 20:07:29 volumio sudo[24177]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "alsa_controller"...
Sep 04 20:07:29 volumio sudo[24177]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:29 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "wizard"...
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "networkfs"...
Sep 04 20:07:29 volumio volumio[24148]: info: Starting Udev Watcher for removable devices
Sep 04 20:07:29 volumio volumio[24148]: info: Ignoring mount for partition: boot
Sep 04 20:07:29 volumio volumio[24148]: info: Ignoring mount for partition: volumio
Sep 04 20:07:29 volumio volumio[24148]: info: Ignoring mount for partition: volumio_data
Sep 04 20:07:29 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "volumio_command_line_client"...
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "upnp"...
Sep 04 20:07:29 volumio volumio[24148]: info: [1757009249576] Starting Upmpd Daemon
Sep 04 20:07:29 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "my_music"...
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "mpd"...
Sep 04 20:07:29 volumio volumio[24148]: info: Plugin upnp_browser is not enabled
Sep 04 20:07:29 volumio volumio[24148]: info: Loading plugin "alarm-clock"...
Sep 04 20:07:30 volumio volumio[24148]: info: Plugin airplay_emulation is not enabled
Sep 04 20:07:30 volumio volumio[24148]: info: Loading plugin "last_100"...
Sep 04 20:07:30 volumio volumio[24148]: info: Loading plugin "webradio"...
Sep 04 20:07:30 volumio volumio[24148]: info: Loading plugin "i2s_dacs"...
Sep 04 20:07:30 volumio volumio[24148]: info: Loading plugin "volumiodiscovery"...
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** For more information see
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:30 volumio volumio[24148]: *** WARNING *** For more information see
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** For more information see
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:30 volumio node[24148]: *** WARNING *** For more information see
Sep 04 20:07:30 volumio volumio[24148]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 04 20:07:30 volumio volumio[24148]: info: Discovery: Started advertising with name: Volumio
Sep 04 20:07:30 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:30 volumio volumio[24148]: info: Loading plugin "jellyfin"...
Sep 04 20:07:30 volumio volumio-remote-updater[504]: [2025-09-04 20:07:30] [connect] Successful connection
Sep 04 20:07:31 volumio volumio[24148]: info: Loading plugin "spop"...
Sep 04 20:07:32 volumio volumio[24148]: info: Loading plugin "ytcr"...
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "outputs"...
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "albumart"...
Sep 04 20:07:36 volumio volumio[24148]: info: Plugin example_plugin is not enabled
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "inputs"...
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "updater_comm"...
Sep 04 20:07:36 volumio volumio[24148]: info: Plugin mpdemulation is not enabled
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "rest_api"...
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "websocket"...
Sep 04 20:07:36 volumio volumio[24148]: info: Starting Socket.io Server version 2.3.0
Sep 04 20:07:36 volumio volumio[24148]: info: Loading plugin "fusiondsp"...
Sep 04 20:07:36 volumio volumio[24148]: Forking 3 albumart workers
Sep 04 20:07:37 volumio volumio[24148]: info: Applying required configuration parameters for plugin fusiondsp
Sep 04 20:07:37 volumio volumio[24148]: info: Loading plugin "Systeminfo"...
Sep 04 20:07:37 volumio volumio[24148]: info: Loading i18n strings for locale hu
Sep 04 20:07:37 volumio volumio[24148]: Updating browse sources language
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::initPlayerControls
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:37 volumio volumio[24148]: Express server listening on port 3000
Sep 04 20:07:37 volumio volumio[24148]: [Metrics] WebUI: 10s 968.93ms
Sep 04 20:07:37 volumio volumio[24148]: info: CoreStateMachine::resetVolumioState
Sep 04 20:07:37 volumio volumio[24148]: info: CoreStateMachine::getcurrentVolume
Sep 04 20:07:37 volumio volumio[24148]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:37 volumio volumio[24148]: Starting albumart workers
Sep 04 20:07:37 volumio volumio[24148]: Starting albumart workers
Sep 04 20:07:37 volumio volumio[24148]: info: Volumio Network Manager: Network status updated: 1
Sep 04 20:07:38 volumio volumio[24148]: Starting albumart workers
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:38 volumio volumio[24148]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::pushState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::updateTrackBlock
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrackBlock
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::setRepeat false single undefined
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::pushState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::setRandom true
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::pushState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:38 volumio volumio[24148]: info: Setting Device type: Raspberry PI
Sep 04 20:07:38 volumio volumio[24148]: info: Listing playlists
Sep 04 20:07:38 volumio volumio[24148]: info: Listing playlists
Sep 04 20:07:38 volumio volumio[24148]: info: Completed loading Core Plugins
Sep 04 20:07:38 volumio volumio[24148]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:38 volumio volumio[24148]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227
Sep 04 20:07:38 volumio volumio[24148]: info: Discovery: Found device Volumio
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:38 volumio volumio[24148]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:38 volumio volumio[24148]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:38 volumio volumio[24148]: info: CoreStateMachine::pushState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:38 volumio volumio[24148]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:38 volumio volumio[24148]: info: Output device has changed, restarting MPD
Sep 04 20:07:38 volumio volumio[24148]: info: ___________ START PLUGINS ___________
Sep 04 20:07:38 volumio sudo[24260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:38 volumio volumio[24148]: info: ControllerMpd::onStart: Initializing MPD
Sep 04 20:07:38 volumio sudo[24260]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:38 volumio sudo[24260]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:38 volumio volumio[24148]: info: Creating MPD Configuration file
Sep 04 20:07:38 volumio sudo[24262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:38 volumio sudo[24262]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:38 volumio systemd[1]: Stopping Music Player Daemon...
Sep 04 20:07:38 volumio sudo[24274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:38 volumio sudo[24274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:38 volumio volumio[24148]: info: [1757009258832] CoreMusicLibrary::Adding element Last_100
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:38 volumio sudo[24274]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:38 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:38 volumio volumio[24148]: info: [1757009258845] CoreMusicLibrary::Adding element Webradio
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:38 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:38 volumio volumio[24148]: info: Initializing BBC Radios
Sep 04 20:07:38 volumio sudo[24277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:38 volumio sudo[24277]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 04 20:07:38 volumio volumio[24148]: info: Discovery: Getting this device information
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:38 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:38 volumio volumio[24148]: info: [1757009258965] CoreMusicLibrary::Adding element Jellyfin
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:38 volumio volumio[24148]: Cannot find translation for source Jellyfin
Sep 04 20:07:38 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:38 volumio volumio[24148]: 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 04 20:07:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Sep 04 20:07:38 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:38 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:38 volumio volumio[24148]: info: Creating Spotify config file
Sep 04 20:07:38 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:38 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:39 volumio sudo[24291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:39 volumio sudo[24291]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:39 volumio sudo[24291]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:39 volumio volumio[24148]: info: Loading i18n strings for locale hu
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: FusionDsp - mixtype--------------------- Hardware
Sep 04 20:07:39 volumio volumio[24148]: info: Volumio Calling Home
Sep 04 20:07:39 volumio volumio[24148]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:39 volumio volumio[24148]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:39 volumio volumio[24148]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:39 volumio volumio[24148]: info: MPD Permissions set
Sep 04 20:07:39 volumio volumio[24148]: info: MPD Permissions set
Sep 04 20:07:39 volumio volumio[24148]: info: Spotify config file written
Sep 04 20:07:39 volumio sudo[24302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Sep 04 20:07:39 volumio sudo[24302]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:39 volumio volumio[24148]: 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 04 20:07:39 volumio volumio[24148]: info: Volumio called home
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio systemd[1]: Stopping go-librespot Daemon...
Sep 04 20:07:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:39 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Sep 04 20:07:39 volumio systemd[1]: Stopped go-librespot Daemon.
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio systemd[1]: Started go-librespot Daemon.
Sep 04 20:07:39 volumio go-librespot[24318]: go-librespot daemon starting...
Sep 04 20:07:39 volumio sudo[24302]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:39 volumio volumio[24148]: info: No need to fix Spotify hosts
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=info msg="running go-librespot 0.3.2"
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=debug msg="app state loaded"
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Sep 04 20:07:39 volumio sudo[24331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:39 volumio sudo[24331]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:39 volumio sudo[24331]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:39 volumio sudo[24333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:39 volumio sudo[24333]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:39 volumio sudo[24333]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+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 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+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 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=info msg="zeroconf server listening on port 39629"
Sep 04 20:07:39 volumio go-librespot[24318]: time="2025-09-04T20:07:39+02:00" level=debug msg="obtained new client token: AABO8MPuu0TeAHwqoxV63dUb3U/wybeXZFzxUI0N6GdbO6c/W3z5ZCdRsGtCTpHDgDgVxi1SfPrZz/JlKHObgfIrIzN1qM2J8rLYybpFEMzozVRaYWlhkGW5ZwQq8L0639A+stBiyzVFL9Ch+xcPx7ymxTXkIGTpnTD549DeKycEnq2RmZBHadaKGnu50H5kgJMw3BzdLgY89IMAfZdx++eeR/PdN4t3j0mbiR00NgWM2zknrow/a1i70bA="
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="completed keyexchange"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="completed challenge"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=info msg="authenticated AP" username="lo*********************vv"
Sep 04 20:07:40 volumio volumio[24148]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:40 volumio volumio[24148]: SPOTIFY: BQBq7NzE_S3NktijD8MC8ySwb6QAwzrTzQY62sSPDfnt9tp9E8t7Kr4mXwhWcuaYEa_sUe1UtNmUlvVzfV3na9DIs8buzJ6wkIOaSeX7fOPJlaHzFJaJsNoNHqL_Uet3yj8qbGmJB6yjZZJKvWxesDW8Sd9UdyVjQ_id0_THVDdVtAetL_ecPhcMCPy56TSTsZz-hZ-gfLQnHAhfTeXa7KaavD8nB8UDeUAeogZQmHTDt_5VQCfIPMl1SYSsQkAnw4T-iPRh3uDsIAcSajj7YEYaODLbUzlUgQh24iBL42ZCZLo
Sep 04 20:07:40 volumio volumio[24148]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:40 volumio volumio[24148]: info: New Spotify access token = BQBq7NzE_S3NktijD8MC8ySwb6QAwzrTzQY62sSPDfnt9tp9E8t7Kr4mXwhWcuaYEa_sUe1UtNmUlvVzfV3na9DIs8buzJ6wkIOaSeX7fOPJlaHzFJaJsNoNHqL_Uet3yj8qbGmJB6yjZZJKvWxesDW8Sd9UdyVjQ_id0_THVDdVtAetL_ecPhcMCPy56TSTsZz-hZ-gfLQnHAhfTeXa7KaavD8nB8UDeUAeogZQmHTDt_5VQCfIPMl1SYSsQkAnw4T-iPRh3uDsIAcSajj7YEYaODLbUzlUgQh24iBL42ZCZLo
Sep 04 20:07:40 volumio volumio[24148]: info: Spotify credentials grant success - running version from March 24, 2019
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:40 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=info msg="authenticated Login5" username="lo*********************vv"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv"
Sep 04 20:07:40 volumio volumio[24148]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:40 volumio volumio[24148]: info: Output device has changed, restarting MPD
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="dealer connection opened"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=trace msg="starting accesspoint recv loop"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=trace msg="starting dealer recv loop"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=trace msg="received accesspoint ping"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="received connection id: NGRkMWI3MTAtNTg4...MUQ4MTAwREUzMw=="
Sep 04 20:07:40 volumio sudo[24337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:40 volumio sudo[24337]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=trace msg="received accesspoint pong ack"
Sep 04 20:07:40 volumio sudo[24337]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:40 volumio sudo[24339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:40 volumio sudo[24339]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:40 volumio volumio[24148]: info: MPD Permissions set
Sep 04 20:07:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:40 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:40 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:40 volumio sudo[24343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 04 20:07:40 volumio sudo[24343]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:40 volumio go-librespot[24318]: time="2025-09-04T20:07:40+02:00" level=debug msg="put connect state because NEW_DEVICE"
Sep 04 20:07:40 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:40 volumio sudo[24343]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:40 volumio volumio[24148]: info: Upmpdcli Daemon Started
Sep 04 20:07:40 volumio volumio[24148]: 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 04 20:07:40 volumio volumio[24148]: info: Spotify Successfully logged in
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:40 volumio volumio[24148]: info: [1757009260548] CoreMusicLibrary::Adding element Spotify
Sep 04 20:07:40 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:40 volumio volumio[24148]: Cannot find translation for source Jellyfin
Sep 04 20:07:40 volumio volumio[24148]: Cannot find translation for source Spotify
Sep 04 20:07:40 volumio sudo[24344]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:40 volumio sudo[24344]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:40 volumio sudo[24344]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:40 volumio volumio[24148]: info: [yt-cast-receiver] DIAL server listening on port 8098
Sep 04 20:07:41 volumio volumio[24148]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:41 volumio volumio[24148]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:41 volumio volumio[24148]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:41 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:41 volumio volumio[24148]: info: CoreStateMachine::pushState
Sep 04 20:07:41 volumio volumio[24148]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:41 volumio volumio[24148]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:41 volumio volumio[24148]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:41 volumio volumio[24148]: 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 04 20:07:41 volumio volumio[24148]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:41 volumio volumio[24148]: info: camilladsp service started and running in background, instance 1
Sep 04 20:07:41 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:41 volumio volumio[24148]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:41 volumio volumio[24148]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Sep 04 20:07:41 volumio volumio[24148]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:41 volumio volumio[24148]: info: FusionDsp loaded
Sep 04 20:07:41 volumio volumio[24148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:41 volumio sudo[24367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Sep 04 20:07:41 volumio sudo[24367]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:41 volumio sudo[24367]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:41 volumio volumio[24148]: info: FusionDsp - Reporting Fusion DSP Enabled
Sep 04 20:07:41 volumio volumio[24148]: info: Adding Signal Path Element [object Object]
Sep 04 20:07:41 volumio volumio[24148]: info: Adding fusiondspeq DSP Signal Path Element
Sep 04 20:07:41 volumio volumio[24148]: info: FusionDsp - ---- installed callbackRead
Sep 04 20:07:41 volumio volumio[24148]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:41 volumio volumio[24148]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Sep 04 20:07:41 volumio volumio[24148]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Sep 04 20:07:41 volumio volumio[24148]: at onErrorNT (internal/child_process.js:465:16)
Sep 04 20:07:41 volumio volumio[24148]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Sep 04 20:07:41 volumio volumio[24148]: at runNextTicks (internal/process/task_queues.js:62:3)
Sep 04 20:07:41 volumio volumio[24148]: at listOnTimeout (internal/timers.js:523:9)
Sep 04 20:07:41 volumio volumio[24148]: at processTimers (internal/timers.js:497:7) {
Sep 04 20:07:41 volumio volumio[24148]: errno: -2,
Sep 04 20:07:41 volumio volumio[24148]: code: 'ENOENT',
Sep 04 20:07:41 volumio volumio[24148]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:41 volumio volumio[24148]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:41 volumio volumio[24148]: spawnargs: [
Sep 04 20:07:41 volumio volumio[24148]: '-p',
Sep 04 20:07:41 volumio volumio[24148]: 9876,
Sep 04 20:07:41 volumio volumio[24148]: '-o',
Sep 04 20:07:41 volumio volumio[24148]: '/tmp/camilladsp.log',
Sep 04 20:07:41 volumio volumio[24148]: '-l',
Sep 04 20:07:41 volumio volumio[24148]: 'warn',
Sep 04 20:07:41 volumio volumio[24148]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Sep 04 20:07:41 volumio volumio[24148]: ]
Sep 04 20:07:41 volumio volumio[24148]: }
Sep 04 20:07:41 volumio volumio[24148]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:42 volumio sudo[24378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 20:06
Sep 04 20:07:42 volumio sudo[24378]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:42 volumio sudo[24378]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:42 volumio mpd[24352]: Sep 04 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 04 20:07:42 volumio systemd[1]: Started Music Player Daemon.
Sep 04 20:07:42 volumio sudo[24262]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:42 volumio sudo[24339]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:42 volumio sudo[24277]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Sep 04 20:07:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Sep 04 20:07:42 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:42 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Sep 04 20:07:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14775.
Sep 04 20:07:42 volumio systemd[1]: Stopped Volumio Backend Module.
Sep 04 20:07:42 volumio systemd[1]: Started Volumio Backend Module.
Sep 04 20:07:42 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:42 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:42 volumio volumio-remote-updater[504]: [2025-09-04 20:07:42] [info] asio async_connect error: system:111 (Connection refused)
Sep 04 20:07:42 volumio volumio-remote-updater[504]: [2025-09-04 20:07:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Sep 04 20:07:42 volumio volumio-remote-updater[504]: [2025-09-04 20:07:42] [error] handle_connect error: Underlying Transport Error
Sep 04 20:07:44 volumio volumio[24391]: info: -------------------------------------------
Sep 04 20:07:44 volumio volumio[24391]: info: ----- Volumio3 ----
Sep 04 20:07:44 volumio volumio[24391]: info: -------------------------------------------
Sep 04 20:07:44 volumio volumio[24391]: info: ----- System startup ----
Sep 04 20:07:44 volumio volumio[24391]: info: -------------------------------------------
Sep 04 20:07:45 volumio volumio[24391]: info: MYVOLUMIO Environment detected
Sep 04 20:07:45 volumio volumio[24391]: info: Plugin folders cleanup
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning into folder /volumio/app/plugins/
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category audio_interface
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category miscellanea
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category music_service
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category plugins.json
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category system_controller
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category user_interface
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning into folder /data/plugins/
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category audio_interface
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category music_service
Sep 04 20:07:45 volumio volumio[24391]: info: Scanning category user_interface
Sep 04 20:07:45 volumio volumio[24391]: info: Plugin folders cleanup completed
Sep 04 20:07:45 volumio volumio[24391]: info: -------------------------------------------
Sep 04 20:07:45 volumio volumio[24391]: info: ----- Core plugins startup ----
Sep 04 20:07:45 volumio volumio[24391]: info: -------------------------------------------
Sep 04 20:07:45 volumio volumio[24391]: info: Loading plugins from folder /volumio/app/plugins/
Sep 04 20:07:45 volumio volumio[24391]: info: Adding plugin upnp to MyMusic Plugins
Sep 04 20:07:45 volumio volumio[24391]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 04 20:07:45 volumio volumio[24391]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 04 20:07:45 volumio volumio[24391]: info: Loading plugins from folder /data/plugins/
Sep 04 20:07:45 volumio volumio[24391]: info: Loading plugin "system"...
Sep 04 20:07:45 volumio volumio[24391]: info: Loading plugin "appearance"...
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "network"...
Sep 04 20:07:46 volumio volumio[24391]: info: Refreshing Cached IP Addresses
Sep 04 20:07:46 volumio sudo[24418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:46 volumio sudo[24418]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:46 volumio sudo[24418]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:46 volumio sudo[24420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "services"...
Sep 04 20:07:46 volumio sudo[24420]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "alsa_controller"...
Sep 04 20:07:46 volumio sudo[24420]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:46 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "wizard"...
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "networkfs"...
Sep 04 20:07:46 volumio volumio[24391]: info: Starting Udev Watcher for removable devices
Sep 04 20:07:46 volumio volumio[24391]: info: Ignoring mount for partition: boot
Sep 04 20:07:46 volumio volumio[24391]: info: Ignoring mount for partition: volumio
Sep 04 20:07:46 volumio volumio[24391]: info: Ignoring mount for partition: volumio_data
Sep 04 20:07:46 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "volumio_command_line_client"...
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "upnp"...
Sep 04 20:07:46 volumio volumio[24391]: info: [1757009266590] Starting Upmpd Daemon
Sep 04 20:07:46 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "my_music"...
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "mpd"...
Sep 04 20:07:46 volumio volumio[24391]: info: Plugin upnp_browser is not enabled
Sep 04 20:07:46 volumio volumio[24391]: info: Loading plugin "alarm-clock"...
Sep 04 20:07:47 volumio volumio[24391]: info: Plugin airplay_emulation is not enabled
Sep 04 20:07:47 volumio volumio[24391]: info: Loading plugin "last_100"...
Sep 04 20:07:47 volumio volumio[24391]: info: Loading plugin "webradio"...
Sep 04 20:07:47 volumio volumio[24391]: info: Loading plugin "i2s_dacs"...
Sep 04 20:07:47 volumio volumio[24391]: info: Loading plugin "volumiodiscovery"...
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** For more information see
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:47 volumio volumio[24391]: *** WARNING *** For more information see
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** For more information see
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:07:47 volumio node[24391]: *** WARNING *** For more information see
Sep 04 20:07:47 volumio volumio[24391]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 04 20:07:47 volumio volumio[24391]: info: Discovery: Started advertising with name: Volumio
Sep 04 20:07:47 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:47 volumio volumio[24391]: info: Loading plugin "jellyfin"...
Sep 04 20:07:48 volumio volumio[24391]: info: Loading plugin "spop"...
Sep 04 20:07:49 volumio volumio[24391]: info: Loading plugin "ytcr"...
Sep 04 20:07:52 volumio volumio-remote-updater[504]: [2025-09-04 20:07:52] [connect] Successful connection
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "outputs"...
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "albumart"...
Sep 04 20:07:53 volumio volumio[24391]: info: Plugin example_plugin is not enabled
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "inputs"...
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "updater_comm"...
Sep 04 20:07:53 volumio volumio[24391]: info: Plugin mpdemulation is not enabled
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "rest_api"...
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "websocket"...
Sep 04 20:07:53 volumio volumio[24391]: info: Starting Socket.io Server version 2.3.0
Sep 04 20:07:53 volumio volumio[24391]: info: Loading plugin "fusiondsp"...
Sep 04 20:07:53 volumio volumio[24391]: Forking 3 albumart workers
Sep 04 20:07:54 volumio volumio[24391]: info: Applying required configuration parameters for plugin fusiondsp
Sep 04 20:07:54 volumio volumio[24391]: info: Loading plugin "Systeminfo"...
Sep 04 20:07:54 volumio volumio[24391]: info: Loading i18n strings for locale hu
Sep 04 20:07:54 volumio volumio[24391]: Updating browse sources language
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::initPlayerControls
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:07:54 volumio volumio[24391]: Express server listening on port 3000
Sep 04 20:07:54 volumio volumio[24391]: [Metrics] WebUI: 10s 960.37ms
Sep 04 20:07:54 volumio volumio[24391]: info: CoreStateMachine::resetVolumioState
Sep 04 20:07:54 volumio volumio[24391]: info: CoreStateMachine::getcurrentVolume
Sep 04 20:07:54 volumio volumio[24391]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:54 volumio volumio[24391]: Starting albumart workers
Sep 04 20:07:54 volumio volumio[24391]: info: Volumio Network Manager: Network status updated: 1
Sep 04 20:07:55 volumio volumio[24391]: Starting albumart workers
Sep 04 20:07:55 volumio volumio[24391]: Starting albumart workers
Sep 04 20:07:55 volumio volumio-remote-updater[504]: [2025-09-04 20:07:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757009272 101
Sep 04 20:07:55 volumio volumio[24391]: 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 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:55 volumio volumio[24391]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::pushState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::updateTrackBlock
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrackBlock
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::setRepeat false single undefined
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::pushState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::setRandom true
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::pushState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:55 volumio volumio[24391]: info: Setting Device type: Raspberry PI
Sep 04 20:07:55 volumio volumio[24391]: info: Listing playlists
Sep 04 20:07:55 volumio volumio[24391]: info: Listing playlists
Sep 04 20:07:55 volumio volumio[24391]: info: Completed loading Core Plugins
Sep 04 20:07:55 volumio volumio[24391]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:55 volumio volumio[24391]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:55 volumio volumio[24391]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:55 volumio volumio[24391]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:55 volumio volumio[24391]: info: CoreStateMachine::pushState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:55 volumio volumio[24391]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227
Sep 04 20:07:55 volumio volumio[24391]: info: Discovery: Found device Volumio
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:55 volumio volumio[24391]: info: Output device has changed, restarting MPD
Sep 04 20:07:55 volumio sudo[24488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:55 volumio sudo[24488]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:55 volumio volumio[24391]: info: ___________ START PLUGINS ___________
Sep 04 20:07:55 volumio sudo[24488]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:55 volumio volumio[24391]: info: ControllerMpd::onStart: Initializing MPD
Sep 04 20:07:55 volumio volumio[24391]: info: Creating MPD Configuration file
Sep 04 20:07:55 volumio sudo[24495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:55 volumio sudo[24495]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:55 volumio sudo[24501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:55 volumio sudo[24501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:55 volumio volumio[24391]: info: [1757009275760] CoreMusicLibrary::Adding element Last_100
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:55 volumio volumio[24391]: info: [1757009275769] CoreMusicLibrary::Adding element Webradio
Sep 04 20:07:55 volumio sudo[24501]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:55 volumio volumio[24391]: info: Initializing BBC Radios
Sep 04 20:07:55 volumio systemd[1]: Stopping Music Player Daemon...
Sep 04 20:07:55 volumio sudo[24506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:55 volumio sudo[24506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:55 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:55 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:55 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Sep 04 20:07:55 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:55 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:07:55 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 04 20:07:55 volumio volumio[24391]: info: Discovery: Getting this device information
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:55 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:55 volumio volumio[24391]: info: [1757009275966] CoreMusicLibrary::Adding element Jellyfin
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:55 volumio volumio[24391]: Cannot find translation for source Jellyfin
Sep 04 20:07:55 volumio volumio[24391]: 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 04 20:07:55 volumio volumio[24391]: info: Creating Spotify config file
Sep 04 20:07:55 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio sudo[24535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:56 volumio sudo[24535]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:56 volumio sudo[24535]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:56 volumio volumio[24391]: info: Loading i18n strings for locale hu
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: FusionDsp - mixtype--------------------- Hardware
Sep 04 20:07:56 volumio volumio[24391]: info: Volumio Calling Home
Sep 04 20:07:56 volumio volumio[24391]: info: Preparing to generate the ALSA configuration file
Sep 04 20:07:56 volumio volumio[24391]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:07:56 volumio volumio[24391]: info: Reading ALSA contributions from plugins.
Sep 04 20:07:56 volumio volumio[24391]: info: MPD Permissions set
Sep 04 20:07:56 volumio volumio[24391]: info: MPD Permissions set
Sep 04 20:07:56 volumio volumio[24391]: info: Spotify config file written
Sep 04 20:07:56 volumio volumio[24391]: 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 04 20:07:56 volumio sudo[24542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Sep 04 20:07:56 volumio volumio[24391]: info: Volumio called home
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio sudo[24542]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio systemd[1]: Stopping go-librespot Daemon...
Sep 04 20:07:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:56 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Sep 04 20:07:56 volumio systemd[1]: Stopped go-librespot Daemon.
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:56 volumio volumio[24391]: info: No need to fix Spotify hosts
Sep 04 20:07:56 volumio systemd[1]: Started go-librespot Daemon.
Sep 04 20:07:56 volumio go-librespot[24562]: go-librespot daemon starting...
Sep 04 20:07:56 volumio sudo[24542]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=info msg="running go-librespot 0.3.2"
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=debug msg="app state loaded"
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Sep 04 20:07:56 volumio sudo[24575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:07:56 volumio sudo[24575]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:56 volumio sudo[24575]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:56 volumio sudo[24578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:07:56 volumio sudo[24578]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:56 volumio sudo[24578]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Sep 04 20:07:56 volumio go-librespot[24562]: time="2025-09-04T20:07:56+02:00" level=info msg="zeroconf server listening on port 46331"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="obtained new client token: AABz7wYfALAYuHfm3dMMog+WuBHsOm0EclTXEK1/g9Z/a1+JmobR+S4rqnNxfiuSOgLOu499z1ngvbVpyanmzVAURjCAk6IToqmYRo5aRfFIXyo+Z5MJPUbFyxMDOdIBc2cMz4f0+4SQY/eTa/tf/gx1bfUzrkQe68rcHitJuu9ovgEEO+rdZ4uKHl+YWZOeFbc8/nUVcM2CJEyQEVcfTKpRswEnMQMDm83VGeYTl7eSwl357la1KIjm"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Sep 04 20:07:57 volumio volumio[24391]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:57 volumio volumio[24391]: SPOTIFY: BQDNb3ntJjFPiew_lkwsMPalH01woTM3ht_jvXqVVbDCOmNCyUF5EXHkzdBoDuvnHEjdy7TeSHVKoSUL6T2zG_7bmdbQiCowRLU84EP1oX_LGa1HXlZIjVvDYOV-JtaWdy3hcyj_ACoQY9jrvbysDkUtRp-U6qRYr1eV9h6jcWLUHhAHM6f9Rwr1tyxn24KSZKxHsvqNkZHKZhG5Uu3NpkBG_5OWBUUVOdGVTFETpd3M5QvydWBbdul_aINVMSVYMOd0bgec3gY473wvueLJ5uLm_2_I9rFBKfEGueAMnZHsQuk
Sep 04 20:07:57 volumio volumio[24391]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:07:57 volumio volumio[24391]: info: New Spotify access token = BQDNb3ntJjFPiew_lkwsMPalH01woTM3ht_jvXqVVbDCOmNCyUF5EXHkzdBoDuvnHEjdy7TeSHVKoSUL6T2zG_7bmdbQiCowRLU84EP1oX_LGa1HXlZIjVvDYOV-JtaWdy3hcyj_ACoQY9jrvbysDkUtRp-U6qRYr1eV9h6jcWLUHhAHM6f9Rwr1tyxn24KSZKxHsvqNkZHKZhG5Uu3NpkBG_5OWBUUVOdGVTFETpd3M5QvydWBbdul_aINVMSVYMOd0bgec3gY473wvueLJ5uLm_2_I9rFBKfEGueAMnZHsQuk
Sep 04 20:07:57 volumio volumio[24391]: info: Spotify credentials grant success - running version from March 24, 2019
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="completed keyexchange"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="completed challenge"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=info msg="authenticated AP" username="lo*********************vv"
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:57 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:57 volumio volumio[24391]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:07:57 volumio volumio[24391]: info: Output device has changed, restarting MPD
Sep 04 20:07:57 volumio sudo[24581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:07:57 volumio sudo[24581]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:57 volumio sudo[24581]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=info msg="authenticated Login5" username="lo*********************vv"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv"
Sep 04 20:07:57 volumio sudo[24584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:07:57 volumio sudo[24584]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:07:57 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:07:57 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="dealer connection opened"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=trace msg="starting accesspoint recv loop"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=trace msg="starting dealer recv loop"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=trace msg="received accesspoint ping"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Sep 04 20:07:57 volumio volumio[24391]: info: MPD Permissions set
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="received connection id: MGI1ODg1MjktOTg0...RkIxRjhGQzFBOA=="
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=trace msg="received accesspoint pong ack"
Sep 04 20:07:57 volumio sudo[24588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 04 20:07:57 volumio sudo[24588]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:57 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:07:57 volumio sudo[24588]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio go-librespot[24562]: time="2025-09-04T20:07:57+02:00" level=debug msg="put connect state because NEW_DEVICE"
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:57 volumio volumio[24391]: info: Upmpdcli Daemon Started
Sep 04 20:07:57 volumio volumio[24391]: 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 04 20:07:57 volumio volumio[24391]: info: Spotify Successfully logged in
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:07:57 volumio volumio[24391]: info: [1757009277535] CoreMusicLibrary::Adding element Spotify
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:07:57 volumio volumio[24391]: Cannot find translation for source Jellyfin
Sep 04 20:07:57 volumio volumio[24391]: Cannot find translation for source Spotify
Sep 04 20:07:57 volumio sudo[24586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:07:57 volumio sudo[24586]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:57 volumio sudo[24586]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:57 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:57 volumio volumio[24391]: info: [yt-cast-receiver] DIAL server listening on port 8098
Sep 04 20:07:57 volumio volumio[24391]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:07:58 volumio volumio[24391]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:07:58 volumio volumio[24391]: info: CoreCommandRouter::volumioGetState
Sep 04 20:07:58 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:58 volumio volumio[24391]: info: CoreStateMachine::pushState
Sep 04 20:07:58 volumio volumio[24391]: info: CorePlayQueue::getTrack 0
Sep 04 20:07:58 volumio volumio[24391]: info: CoreCommandRouter::volumioPushState
Sep 04 20:07:58 volumio volumio[24391]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:58 volumio volumio[24391]: 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 04 20:07:58 volumio volumio[24391]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Sep 04 20:07:58 volumio volumio[24391]: info: camilladsp service started and running in background, instance 1
Sep 04 20:07:58 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:07:58 volumio volumio[24391]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:58 volumio volumio[24391]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Sep 04 20:07:58 volumio volumio[24391]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Sep 04 20:07:58 volumio volumio[24391]: info: FusionDsp loaded
Sep 04 20:07:58 volumio volumio[24391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:07:58 volumio sudo[24609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Sep 04 20:07:58 volumio sudo[24609]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:58 volumio sudo[24609]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:58 volumio volumio[24391]: info: FusionDsp - Reporting Fusion DSP Enabled
Sep 04 20:07:58 volumio volumio[24391]: info: Adding Signal Path Element [object Object]
Sep 04 20:07:58 volumio volumio[24391]: info: Adding fusiondspeq DSP Signal Path Element
Sep 04 20:07:58 volumio volumio[24391]: info: FusionDsp - ---- installed callbackRead
Sep 04 20:07:58 volumio volumio[24391]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:58 volumio volumio[24391]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Sep 04 20:07:58 volumio volumio[24391]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Sep 04 20:07:58 volumio volumio[24391]: at onErrorNT (internal/child_process.js:465:16)
Sep 04 20:07:58 volumio volumio[24391]: at processTicksAndRejections (internal/process/task_queues.js:80:21) {
Sep 04 20:07:58 volumio volumio[24391]: errno: -2,
Sep 04 20:07:58 volumio volumio[24391]: code: 'ENOENT',
Sep 04 20:07:58 volumio volumio[24391]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:58 volumio volumio[24391]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Sep 04 20:07:58 volumio volumio[24391]: spawnargs: [
Sep 04 20:07:58 volumio volumio[24391]: '-p',
Sep 04 20:07:58 volumio volumio[24391]: 9876,
Sep 04 20:07:58 volumio volumio[24391]: '-o',
Sep 04 20:07:58 volumio volumio[24391]: '/tmp/camilladsp.log',
Sep 04 20:07:58 volumio volumio[24391]: '-l',
Sep 04 20:07:58 volumio volumio[24391]: 'warn',
Sep 04 20:07:58 volumio volumio[24391]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Sep 04 20:07:58 volumio volumio[24391]: ]
Sep 04 20:07:58 volumio volumio[24391]: }
Sep 04 20:07:58 volumio volumio[24391]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:07:59 volumio sudo[24620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 20:06
Sep 04 20:07:59 volumio sudo[24620]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:07:59 volumio mpd[24596]: Sep 04 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Sep 04 20:07:59 volumio systemd[1]: Started Music Player Daemon.
Sep 04 20:07:59 volumio sudo[24506]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:59 volumio sudo[24584]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:59 volumio sudo[24495]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:59 volumio sudo[24620]: pam_unix(sudo:session): session closed for user root
Sep 04 20:07:59 volumio volumio-remote-updater[504]: [2025-09-04 20:07:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Sep 04 20:07:59 volumio volumio-remote-updater[504]: [2025-09-04 20:07:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Sep 04 20:07:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Sep 04 20:07:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Sep 04 20:07:59 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:59 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:07:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Sep 04 20:07:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14776.
Sep 04 20:07:59 volumio systemd[1]: Stopped Volumio Backend Module.
Sep 04 20:07:59 volumio systemd[1]: Started Volumio Backend Module.
Sep 04 20:07:59 volumio systemd[1]: Started dynamicswap service.
Sep 04 20:07:59 volumio systemd[1]: dynamicswap.service: Succeeded.
Sep 04 20:08:01 volumio volumio[24633]: info: -------------------------------------------
Sep 04 20:08:01 volumio volumio[24633]: info: ----- Volumio3 ----
Sep 04 20:08:01 volumio volumio[24633]: info: -------------------------------------------
Sep 04 20:08:01 volumio volumio[24633]: info: ----- System startup ----
Sep 04 20:08:01 volumio volumio[24633]: info: -------------------------------------------
Sep 04 20:08:02 volumio volumio[24633]: info: MYVOLUMIO Environment detected
Sep 04 20:08:02 volumio volumio[24633]: info: Plugin folders cleanup
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning into folder /volumio/app/plugins/
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category audio_interface
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category miscellanea
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category music_service
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category plugins.json
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category system_controller
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category user_interface
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning into folder /data/plugins/
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category audio_interface
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category music_service
Sep 04 20:08:02 volumio volumio[24633]: info: Scanning category user_interface
Sep 04 20:08:02 volumio volumio[24633]: info: Plugin folders cleanup completed
Sep 04 20:08:02 volumio volumio[24633]: info: -------------------------------------------
Sep 04 20:08:02 volumio volumio[24633]: info: ----- Core plugins startup ----
Sep 04 20:08:02 volumio volumio[24633]: info: -------------------------------------------
Sep 04 20:08:02 volumio volumio[24633]: info: Loading plugins from folder /volumio/app/plugins/
Sep 04 20:08:02 volumio volumio[24633]: info: Adding plugin upnp to MyMusic Plugins
Sep 04 20:08:02 volumio volumio[24633]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 04 20:08:02 volumio volumio[24633]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 04 20:08:02 volumio volumio[24633]: info: Loading plugins from folder /data/plugins/
Sep 04 20:08:02 volumio volumio[24633]: info: Loading plugin "system"...
Sep 04 20:08:02 volumio volumio[24633]: info: Loading plugin "appearance"...
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "network"...
Sep 04 20:08:03 volumio volumio[24633]: info: Refreshing Cached IP Addresses
Sep 04 20:08:03 volumio sudo[24664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:08:03 volumio sudo[24664]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:03 volumio sudo[24664]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:03 volumio sudo[24666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "services"...
Sep 04 20:08:03 volumio sudo[24666]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "alsa_controller"...
Sep 04 20:08:03 volumio sudo[24666]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:03 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "wizard"...
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "networkfs"...
Sep 04 20:08:03 volumio volumio[24633]: info: Starting Udev Watcher for removable devices
Sep 04 20:08:03 volumio volumio[24633]: info: Ignoring mount for partition: boot
Sep 04 20:08:03 volumio volumio[24633]: info: Ignoring mount for partition: volumio
Sep 04 20:08:03 volumio volumio[24633]: info: Ignoring mount for partition: volumio_data
Sep 04 20:08:03 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "volumio_command_line_client"...
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "upnp"...
Sep 04 20:08:03 volumio volumio[24633]: info: [1757009283613] Starting Upmpd Daemon
Sep 04 20:08:03 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "my_music"...
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "mpd"...
Sep 04 20:08:03 volumio volumio[24633]: info: Plugin upnp_browser is not enabled
Sep 04 20:08:03 volumio volumio[24633]: info: Loading plugin "alarm-clock"...
Sep 04 20:08:04 volumio volumio[24633]: info: Plugin airplay_emulation is not enabled
Sep 04 20:08:04 volumio volumio[24633]: info: Loading plugin "last_100"...
Sep 04 20:08:04 volumio volumio[24633]: info: Loading plugin "webradio"...
Sep 04 20:08:04 volumio volumio[24633]: info: Loading plugin "i2s_dacs"...
Sep 04 20:08:04 volumio volumio[24633]: info: Loading plugin "volumiodiscovery"...
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** For more information see
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:08:04 volumio volumio[24633]: *** WARNING *** For more information see
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** For more information see
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 04 20:08:04 volumio node[24633]: *** WARNING *** For more information see
Sep 04 20:08:04 volumio volumio[24633]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 04 20:08:04 volumio volumio-remote-updater[504]: [2025-09-04 20:08:04] [connect] Successful connection
Sep 04 20:08:04 volumio volumio[24633]: info: Discovery: Started advertising with name: Volumio
Sep 04 20:08:04 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:08:04 volumio volumio[24633]: info: Loading plugin "jellyfin"...
Sep 04 20:08:05 volumio volumio[24633]: info: Loading plugin "spop"...
Sep 04 20:08:06 volumio volumio[24633]: info: Loading plugin "ytcr"...
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "outputs"...
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "albumart"...
Sep 04 20:08:10 volumio volumio[24633]: info: Plugin example_plugin is not enabled
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "inputs"...
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "updater_comm"...
Sep 04 20:08:10 volumio volumio[24633]: info: Plugin mpdemulation is not enabled
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "rest_api"...
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "websocket"...
Sep 04 20:08:10 volumio volumio[24633]: info: Starting Socket.io Server version 2.3.0
Sep 04 20:08:10 volumio volumio[24633]: info: Loading plugin "fusiondsp"...
Sep 04 20:08:11 volumio volumio[24633]: Forking 3 albumart workers
Sep 04 20:08:11 volumio volumio[24633]: info: Applying required configuration parameters for plugin fusiondsp
Sep 04 20:08:11 volumio volumio[24633]: info: Loading plugin "Systeminfo"...
Sep 04 20:08:11 volumio volumio[24633]: info: Loading i18n strings for locale hu
Sep 04 20:08:11 volumio volumio[24633]: Updating browse sources language
Sep 04 20:08:11 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::initPlayerControls
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: Express server listening on port 3000
Sep 04 20:08:12 volumio volumio[24633]: [Metrics] WebUI: 11s 234.21ms
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::resetVolumioState
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::getcurrentVolume
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:08:12 volumio volumio[24633]: Starting albumart workers
Sep 04 20:08:12 volumio volumio[24633]: Starting albumart workers
Sep 04 20:08:12 volumio volumio[24633]: Starting albumart workers
Sep 04 20:08:12 volumio volumio[24633]: info: Volumio Network Manager: Network status updated: 1
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioGetState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:08:12 volumio volumio[24633]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::pushState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioPushState
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::updateTrackBlock
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrackBlock
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioRetrievevolume
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::setRepeat false single undefined
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::pushState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioPushState
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::setRandom true
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::pushState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioPushState
Sep 04 20:08:12 volumio volumio[24633]: info: Setting Device type: Raspberry PI
Sep 04 20:08:12 volumio volumio[24633]: info: Listing playlists
Sep 04 20:08:12 volumio volumio[24633]: info: Listing playlists
Sep 04 20:08:12 volumio volumio[24633]: info: Completed loading Core Plugins
Sep 04 20:08:12 volumio volumio[24633]: info: Preparing to generate the ALSA configuration file
Sep 04 20:08:12 volumio volumio[24633]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227
Sep 04 20:08:12 volumio volumio[24633]: info: Discovery: Found device Volumio
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioGetState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:08:12 volumio volumio[24633]: info: Reading ALSA contributions from plugins.
Sep 04 20:08:12 volumio volumio[24633]: info: VolumeController:: Volume=37 Mute =false
Sep 04 20:08:12 volumio volumio[24633]: info: CoreStateMachine::pushState
Sep 04 20:08:12 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:12 volumio volumio[24633]: info: CoreCommandRouter::volumioPushState
Sep 04 20:08:12 volumio volumio[24633]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:08:12 volumio volumio[24633]: info: Output device has changed, restarting MPD
Sep 04 20:08:12 volumio sudo[24794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:08:12 volumio sudo[24794]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio volumio[24633]: info: ___________ START PLUGINS ___________
Sep 04 20:08:13 volumio sudo[24794]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio volumio[24633]: info: ControllerMpd::onStart: Initializing MPD
Sep 04 20:08:13 volumio sudo[24796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:08:13 volumio sudo[24796]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio volumio[24633]: info: Creating MPD Configuration file
Sep 04 20:08:13 volumio systemd[1]: Stopping Music Player Daemon...
Sep 04 20:08:13 volumio sudo[24800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:08:13 volumio sudo[24800]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio sudo[24800]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:08:13 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:08:13 volumio volumio[24633]: info: [1757009293104] CoreMusicLibrary::Adding element Last_100
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:08:13 volumio volumio[24633]: info: [1757009293112] CoreMusicLibrary::Adding element Webradio
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:08:13 volumio volumio[24633]: info: Initializing BBC Radios
Sep 04 20:08:13 volumio sudo[24803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:08:13 volumio sudo[24803]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:08:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Sep 04 20:08:13 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:08:13 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:08:13 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 04 20:08:13 volumio volumio[24633]: info: Discovery: Getting this device information
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::volumioGetState
Sep 04 20:08:13 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:08:13 volumio volumio[24633]: info: [1757009293240] CoreMusicLibrary::Adding element Jellyfin
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:08:13 volumio volumio[24633]: Cannot find translation for source Jellyfin
Sep 04 20:08:13 volumio volumio[24633]: 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 04 20:08:13 volumio volumio[24633]: info: Creating Spotify config file
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio sudo[24813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:08:13 volumio sudo[24813]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio sudo[24813]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio volumio[24633]: info: Loading i18n strings for locale hu
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: FusionDsp - mixtype--------------------- Hardware
Sep 04 20:08:13 volumio volumio[24633]: info: Volumio Calling Home
Sep 04 20:08:13 volumio volumio[24633]: info: Preparing to generate the ALSA configuration file
Sep 04 20:08:13 volumio sudo[24837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 04 20:08:13 volumio sudo[24837]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio sudo[24837]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio volumio[24633]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Sep 04 20:08:13 volumio volumio[24633]: info: Reading ALSA contributions from plugins.
Sep 04 20:08:13 volumio sudo[24840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 04 20:08:13 volumio sudo[24840]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio volumio[24633]: info: MPD Permissions set
Sep 04 20:08:13 volumio sudo[24840]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio volumio[24633]: info: MPD Permissions set
Sep 04 20:08:13 volumio volumio[24633]: info: Spotify config file written
Sep 04 20:08:13 volumio sudo[24843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Sep 04 20:08:13 volumio sudo[24843]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:13 volumio volumio[24633]: 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 04 20:08:13 volumio volumio[24633]: info: Volumio called home
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio systemd[1]: Stopping go-librespot Daemon...
Sep 04 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:08:13 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Sep 04 20:08:13 volumio systemd[1]: Stopped go-librespot Daemon.
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio systemd[1]: Started go-librespot Daemon.
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio go-librespot[24845]: go-librespot daemon starting...
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio sudo[24843]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:13 volumio go-librespot[24845]: time="2025-09-04T20:08:13+02:00" level=info msg="running go-librespot 0.3.2"
Sep 04 20:08:13 volumio go-librespot[24845]: time="2025-09-04T20:08:13+02:00" level=debug msg="app state loaded"
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:08:13 volumio go-librespot[24845]: time="2025-09-04T20:08:13+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Sep 04 20:08:13 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:13 volumio volumio[24633]: info: No need to fix Spotify hosts
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+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 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+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 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=info msg="zeroconf server listening on port 34237"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="obtained new client token: AAAlnHi8A1NGW1q2PAMnKRUMLcx46C+CAJK6y6ZuiLjL3IVKVbaQV3ZgkrMi3wZQpf8RocUJLu7/xzsDdonnloZ7uS0oKWihi9Jgbh0o6WeBEwcXwyi18XNDFMuLa6e2VbbBDaPvgDNiz5DZI1Fm8Nkb+51uRsEdiSs5P6Sj8J3HX2byjEKzl5KEdzOOOwXyZeV55bLJ2hRnW+Ooe4GXVvaWb5P3d96MOpBZRXN+ImrwIAi5WqpDVMMFzsU="
Sep 04 20:08:14 volumio volumio[24633]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Sep 04 20:08:14 volumio volumio[24633]: SPOTIFY: BQAjZ7g1njXkQ7a8BRd2cbFJlIp_jUQeTNorTVOsj7nnBDZpA_WDqGb7oi6SwjoTVbRjSOAgUhJezrdZqsgXR4T3fN9v2FZhYT2qel9bujX31LQqC6LdKAnAw2yDAYl24eLRK5FRCiFCY5Xq4Z8Lmdg4jritw7XNLhz_3UOxew-1K_UZwZtNJuAd7yO6EIrraAiWeY9uHRa4hTEj1dUwfUfWRojCqQxynDW0Ayy_ONwPyUnKKj0PWppEmOyXB3LEAfvi3Y3C4R1LN0snpTKC-AtcnJo3jC4I9ThF4_7PKq7RRn4
Sep 04 20:08:14 volumio volumio[24633]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Sep 04 20:08:14 volumio volumio[24633]: info: New Spotify access token = BQAjZ7g1njXkQ7a8BRd2cbFJlIp_jUQeTNorTVOsj7nnBDZpA_WDqGb7oi6SwjoTVbRjSOAgUhJezrdZqsgXR4T3fN9v2FZhYT2qel9bujX31LQqC6LdKAnAw2yDAYl24eLRK5FRCiFCY5Xq4Z8Lmdg4jritw7XNLhz_3UOxew-1K_UZwZtNJuAd7yO6EIrraAiWeY9uHRa4hTEj1dUwfUfWRojCqQxynDW0Ayy_ONwPyUnKKj0PWppEmOyXB3LEAfvi3Y3C4R1LN0snpTKC-AtcnJo3jC4I9ThF4_7PKq7RRn4
Sep 04 20:08:14 volumio volumio[24633]: info: Spotify credentials grant success - running version from March 24, 2019
Sep 04 20:08:14 volumio sudo[24862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="completed keyexchange"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="completed challenge"
Sep 04 20:08:14 volumio sudo[24862]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:14 volumio sudo[24862]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=info msg="authenticated AP" username="lo*********************vv"
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::volumioGetState
Sep 04 20:08:14 volumio volumio[24633]: info: CorePlayQueue::getTrack 0
Sep 04 20:08:14 volumio volumio[24633]: info: Upmpdcli Daemon Started
Sep 04 20:08:14 volumio volumio[24633]: info: Asound.conf file unchanged, so no further update is needed
Sep 04 20:08:14 volumio volumio[24633]: info: Output device has changed, restarting MPD
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=info msg="authenticated Login5" username="lo*********************vv"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv"
Sep 04 20:08:14 volumio sudo[24866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 04 20:08:14 volumio sudo[24866]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:14 volumio sudo[24866]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:14 volumio sudo[24869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 04 20:08:14 volumio sudo[24869]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:14 volumio volumio[24633]: info: MPD Permissions set
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Sep 04 20:08:14 volumio systemd[1]: mpd.service: Succeeded.
Sep 04 20:08:14 volumio systemd[1]: Stopped Music Player Daemon.
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="dealer connection opened"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=trace msg="starting accesspoint recv loop"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=trace msg="received accesspoint ping"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=trace msg="starting dealer recv loop"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="received connection id: ZWRiYTEwMTQtODcx...MDU0RUNBQUI1OA=="
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=trace msg="received accesspoint pong ack"
Sep 04 20:08:14 volumio volumio[24633]: 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 04 20:08:14 volumio volumio[24633]: info: Spotify Successfully logged in
Sep 04 20:08:14 volumio systemd[1]: Starting Music Player Daemon...
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 04 20:08:14 volumio volumio[24633]: info: [1757009294794] CoreMusicLibrary::Adding element Spotify
Sep 04 20:08:14 volumio volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 04 20:08:14 volumio volumio[24633]: Cannot find translation for source Jellyfin
Sep 04 20:08:14 volumio volumio[24633]: Cannot find translation for source Spotify
Sep 04 20:08:14 volumio go-librespot[24845]: time="2025-09-04T20:08:14+02:00" level=debug msg="put connect state because NEW_DEVICE"
Sep 04 20:08:14 volumio sudo[24871]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Sep 04 20:08:14 volumio sudo[24871]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 04 20:08:14 volumio sudo[24871]: pam_unix(sudo:session): session closed for user root
Sep 04 20:08:15 volumio volumio[24633]: info: [yt-cast-receiver] DIAL server listening on port 8098
Sep 04 20:08:15 volumio volumio[24633]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:08:15 volumio volumio[24633]: Error: bind EADDRINUSE 192.168.0.171:50386
Sep 04 20:08:15 volumio volumio[24633]: at dgram.js:337:20
Sep 04 20:08:15 volumio volumio[24633]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
Sep 04 20:08:15 volumio volumio[24633]: errno: -98,
Sep 04 20:08:15 volumio volumio[24633]: code: 'EADDRINUSE',
Sep 04 20:08:15 volumio volumio[24633]: syscall: 'bind',
Sep 04 20:08:15 volumio volumio[24633]: address: '192.168.0.171',
Sep 04 20:08:15 volumio volumio[24633]: port: 50386
Sep 04 20:08:15 volumio volumio[24633]: }
Sep 04 20:08:15 volumio volumio[24633]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 04 20:08:16 volumio sudo[24887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 20:07
Sep 04 20:08:16 volumio sudo[24887]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"