-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Tue 2025-12-30 12:59:39 JST. -- Dec 30 12:59:28 jbl-4312 systemd-timedated[3377]: Changed local time to Tue Dec 30 12:59:28 2025 Dec 30 12:59:28 jbl-4312 sudo[3367]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:28 jbl-4312 volumio-time-update[2836]: volumio-time-update-util: System time updated successfully. Dec 30 12:59:28 jbl-4312 systemd[1]: Starting Daily man-db regeneration... Dec 30 12:59:28 jbl-4312 systemd[1]: Starting Daily apt download activities... Dec 30 12:59:28 jbl-4312 systemd[1]: Started Volumio Time Update Utility. Dec 30 12:59:28 jbl-4312 systemd[1]: Reached target Multi-User System. Dec 30 12:59:28 jbl-4312 systemd[1]: Reached target Graphical Interface. Dec 30 12:59:28 jbl-4312 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 30 12:59:28 jbl-4312 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 30 12:59:28 jbl-4312 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 30 12:59:28 jbl-4312 systemd[1]: man-db.service: Succeeded. Dec 30 12:59:28 jbl-4312 systemd[1]: Started Daily man-db regeneration. Dec 30 12:59:28 jbl-4312 ntpd[3295]: Soliciting pool server 103.131.151.20 Dec 30 12:59:28 jbl-4312 ntpd[3295]: Soliciting pool server 129.250.35.251 Dec 30 12:59:28 jbl-4312 volumio[3106]: info: Loading plugin "qobuz"... Dec 30 12:59:28 jbl-4312 ntpd[3295]: Soliciting pool server 172.105.192.74 Dec 30 12:59:28 jbl-4312 ntpd[3295]: Soliciting pool server 162.159.200.1 Dec 30 12:59:29 jbl-4312 ntpd[3295]: Soliciting pool server 160.25.135.55 Dec 30 12:59:29 jbl-4312 ntpd[3295]: Soliciting pool server 153.127.38.46 Dec 30 12:59:29 jbl-4312 ntpd[3295]: Soliciting pool server 142.91.108.61 Dec 30 12:59:29 jbl-4312 ntpd[3295]: Soliciting pool server 43.167.237.61 Dec 30 12:59:30 jbl-4312 systemd[1]: apt-daily.service: Succeeded. Dec 30 12:59:30 jbl-4312 systemd[1]: Started Daily apt download activities. Dec 30 12:59:30 jbl-4312 systemd[1]: Starting Daily apt upgrade and clean activities... Dec 30 12:59:30 jbl-4312 ntpd[3295]: Soliciting pool server 160.16.113.133 Dec 30 12:59:30 jbl-4312 ntpd[3295]: Soliciting pool server 129.250.35.250 Dec 30 12:59:30 jbl-4312 ntpd[3295]: Soliciting pool server 162.159.200.123 Dec 30 12:59:30 jbl-4312 volumio-remote-updater[2831]: [2025-12-30 12:59:30] [connect] Successful connection Dec 30 12:59:30 jbl-4312 volumio[3106]: info: Plugin smart_inputs is not enabled Dec 30 12:59:30 jbl-4312 volumio[3106]: info: Loading plugin "tidal"... Dec 30 12:59:31 jbl-4312 ntpd[3295]: Soliciting pool server 47.79.37.210 Dec 30 12:59:31 jbl-4312 ntpd[3295]: Soliciting pool server 45.76.211.39 Dec 30 12:59:31 jbl-4312 ntpd[3295]: Soliciting pool server 172.233.91.137 Dec 30 12:59:32 jbl-4312 systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 30 12:59:32 jbl-4312 systemd[1]: Started Daily apt upgrade and clean activities. Dec 30 12:59:32 jbl-4312 systemd[1]: Startup finished in 12.974s (kernel) + 24.422s (userspace) = 37.397s. Dec 30 12:59:32 jbl-4312 ntpd[3295]: Soliciting pool server 2400:8902::2000:12ff:fecd:605 Dec 30 12:59:32 jbl-4312 volumio[3106]: info: Loading plugin "rivopluscontrol"... Dec 30 12:59:32 jbl-4312 volumio[3106]: info: Adding this device properties Dec 30 12:59:32 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 30 12:59:32 jbl-4312 volumio[3106]: info: Setting Additional Device Volumio Properties: [object Object] Dec 30 12:59:32 jbl-4312 volumio[3106]: info: Loading plugin "updater_comm"... Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Loading plugin "cec_controller"... Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Applying required configuration parameters for plugin cec_controller Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Plugin mpdemulation is not enabled Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Loading plugin "rest_api"... Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Loading plugin "websocket"... Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Starting Socket.io Server version 2.3.0 Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Loading plugin "fusiondsp"... Dec 30 12:59:33 jbl-4312 volumio[3106]: info: Applying required configuration parameters for plugin fusiondsp Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Loading i18n strings for locale ja Dec 30 12:59:34 jbl-4312 volumio[3106]: Updating browse sources language Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::initPlayerControls Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: Express server listening on port 3000 Dec 30 12:59:34 jbl-4312 volumio[3106]: [Metrics] WebUI: 18s 536.03ms Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::resetVolumioState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::getcurrentVolume Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 12:59:34 jbl-4312 volumio[3106]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Completed loading Core Plugins Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Preparing to generate the ALSA configuration file Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Cannot read play queue from file Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Volumio Network Manager: Network status updated: 0 Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 30 12:59:34 jbl-4312 volumio[3106]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Reading ALSA contributions from plugins. Dec 30 12:59:34 jbl-4312 volumio-remote-updater[2831]: [2025-12-30 12:59:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767067170 101 Dec 30 12:59:34 jbl-4312 volumio[3106]: 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 Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::setRepeat null single undefined Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::setRandom null Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: VolumeController:: Volume=100 Mute =false Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreStateMachine::updateTrackBlock Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrackBlock Dec 30 12:59:34 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Discovery: A device disappeared from network Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Asound.conf file unchanged, so no further update is needed Dec 30 12:59:34 jbl-4312 volumio[3106]: info: Output device has changed, restarting MPD Dec 30 12:59:34 jbl-4312 sudo[3529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 12:59:34 jbl-4312 sudo[3529]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:34 jbl-4312 volumio[3106]: info: ___________ START PLUGINS ___________ Dec 30 12:59:34 jbl-4312 sudo[3529]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:35 jbl-4312 sudo[3531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 12:59:35 jbl-4312 sudo[3531]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 volumio[3106]: info: ControllerMpd::onStart: Initializing MPD Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Creating MPD Configuration file Dec 30 12:59:35 jbl-4312 sudo[3535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Dec 30 12:59:35 jbl-4312 sudo[3535]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 systemd[1]: Listening on mpd.socket. Dec 30 12:59:35 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 12:59:35 jbl-4312 volumio[3106]: info: [1767067175065] CoreMusicLibrary::Adding element Last_100 Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 12:59:35 jbl-4312 sudo[3538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 12:59:35 jbl-4312 sudo[3538]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Streaming services startup Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Starting Streaming Daemon Dec 30 12:59:35 jbl-4312 sudo[3538]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:35 jbl-4312 systemd[1]: Started Volumio5 Onboarding Server. Dec 30 12:59:35 jbl-4312 sudo[3535]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:35 jbl-4312 sudo[3541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 12:59:35 jbl-4312 sudo[3541]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 12:59:35 jbl-4312 sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 12:59:35 jbl-4312 volumio[3106]: info: [1767067175177] CoreMusicLibrary::Adding element Webradio Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 12:59:35 jbl-4312 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 30 12:59:35 jbl-4312 systemd[1]: mpd.service: Succeeded. Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 12:59:35 jbl-4312 systemd[1]: Stopped Music Player Daemon. Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Initializing BBC Radios Dec 30 12:59:35 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 12:59:35 jbl-4312 sudo[3545]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Creating Spotify config file Dec 30 12:59:35 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:35 jbl-4312 volumio[3106]: error: Hi Res Audio Failed Login: Missing Login Data Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Initializing Serial Communication on port /dev/ttyS3 Dec 30 12:59:35 jbl-4312 volumio[3106]: info: Touch Event Listener Process Starting Dec 30 12:59:35 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Dec 30 12:59:35 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Dec 30 12:59:35 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Dec 30 12:59:35 jbl-4312 sudo[3552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 12:59:35 jbl-4312 sudo[3552]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:35 jbl-4312 sudo[3552]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 12:59:35 jbl-4312 sudo[3552]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.643f1bec does not match aorg 0000000000.00000000 from server@103.131.151.20 xmt 0xecfdcca8.1628aa43 Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.64388b8d does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xecfdcca8.15bf6822 Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.64363f0b does not match aorg 0000000000.00000000 from server@160.25.135.55 xmt 0xecfdcca8.16a8b362 Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.6429e4f3 does not match aorg 0000000000.00000000 from server@129.250.35.250 xmt 0xecfdcca8.15ed8edf Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.644154e3 does not match aorg 0000000000.00000000 from server@167.179.119.205 xmt 0xecfdcca8.167ec31e Dec 30 12:59:36 jbl-4312 sudo[3571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.643390ab does not match aorg 0000000000.00000000 from server@142.91.108.61 xmt 0xecfdcca8.169411ba Dec 30 12:59:36 jbl-4312 ntpd[3295]: receive: Unexpected origin timestamp 0xecfdcca7.643cf5d8 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xecfdcca8.165f9717 Dec 30 12:59:36 jbl-4312 sudo[3571]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3571]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Dec 30 12:59:36 jbl-4312 sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3580]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Dec 30 12:59:36 jbl-4312 sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3583]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:36.288+09:00 level=INFO msg="running volumio5-device-gateway" version=5bca5789+CHANGES buildDate=2025-12-11T14:07:23Z Dec 30 12:59:36 jbl-4312 sudo[3586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Dec 30 12:59:36 jbl-4312 sudo[3586]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3586]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Dec 30 12:59:36 jbl-4312 sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3589]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Apply VIM3L Onboard LEDs Settings Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Turning On Onboard LEDs Dec 30 12:59:36 jbl-4312 sudo[3593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 12:59:36 jbl-4312 sudo[3593]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Starting CEC Event listener Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Loading i18n strings for locale ja Dec 30 12:59:36 jbl-4312 sudo[3593]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 30 12:59:36 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:36 jbl-4312 volumio[3106]: info: FusionDsp - mixtype--------------------- Hardware Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Volumio Calling Home Dec 30 12:59:36 jbl-4312 sudo[3597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 12:59:36 jbl-4312 sudo[3597]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:36 jbl-4312 sudo[3597]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Preparing to generate the ALSA configuration file Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Stopping AccessToken refresher cron for QOBUZ Dec 30 12:59:36 jbl-4312 volumio[3106]: info: AccessToken refresher cron started for QOBUZ Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Adding TIDAL REST API Endpoints Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Adding QOBUZ REST API Endpoints Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Discovery: adding 477e6b25-e4ef-4cfb-b828-303619ebc483 Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Discovery: Found device JBL 4312 Dec 30 12:59:36 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:36 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Serial port opened successfully Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Sending serial start messages Dec 30 12:59:36 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Reporting MCU Network Status: 0 Dec 30 12:59:36 jbl-4312 volumio[3106]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Reading ALSA contributions from plugins. Dec 30 12:59:36 jbl-4312 volumio[3106]: info: Touch Event Listener Process Closed Dec 30 12:59:36 jbl-4312 volumio[3106]: error: Cannot start Volumio Streaming Daemon Dec 30 12:59:36 jbl-4312 volumio[3106]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 12:59:36 jbl-4312 volumio[3106]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 12:59:36 jbl-4312 volumio[3106]: info: MPD Permissions set Dec 30 12:59:36 jbl-4312 volumio[3106]: info: MPD Permissions set Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting Device Volume Override Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: SMSL USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Disabling external Volume Control Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:37 jbl-4312 volumio[3106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Spotify config file written Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting Additional System Software info: Hardware Revision: 1.0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting HW Firmware info: undefined Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting HW Version info: 1.0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting HW Firmware info: 0.3.0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Setting HW Version info: 1.0 Dec 30 12:59:37 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:37.209+09:00 level=INFO msg="listening for BLE messages" address=B8:13:32:E4:3F:CF%00 Dec 30 12:59:37 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:37.210+09:00 level=INFO msg="listening for WebSocket messages" address=[::]:7331 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 12:59:37 jbl-4312 volumio[3106]: info: MCU Signalled Headphone Mode Disabled Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: MCU Signalled Sleep Mode Disabled Dec 30 12:59:37 jbl-4312 sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 12:59:37 jbl-4312 sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Enabling Advanced system settings configuration Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 12:59:37 jbl-4312 volumio[3106]: info: MCU Signalled Auto Boot Mode On Power Disabled Dec 30 12:59:37 jbl-4312 sudo[3615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 30 12:59:37 jbl-4312 sudo[3615]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:37 jbl-4312 systemd[1]: Started go-librespot Daemon. Dec 30 12:59:37 jbl-4312 sudo[3615]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:37 jbl-4312 sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:37 jbl-4312 volumio[3106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Dec 30 12:59:37 jbl-4312 go-librespot[3617]: go-librespot daemon starting... Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Received Get System Info Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Discovery: Getting this device information Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 12:59:37 jbl-4312 volumio[3106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Volumio called home Dec 30 12:59:37 jbl-4312 volumio[3106]: info: VolumeController:: Volume=100 Mute =false Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: No need to fix Spotify hosts Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=info msg="running go-librespot 0.4.0" Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=debug msg="app state loaded" Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=debug msg="stored credentials not found" Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:37 jbl-4312 volumio[3106]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Discovery: this is already registered, 477e6b25-e4ef-4cfb-b828-303619ebc483 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Discovery: Found device JBL 4312 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 12:59:37 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:37.640+09:00 level=INFO msg="BLE descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName="JBL 4312" deviceModel=0x1c278c8 Dec 30 12:59:37 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:37.652+09:00 level=INFO msg="mDNS descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName="JBL 4312" deviceModel=0x1c278c8 Dec 30 12:59:37 jbl-4312 volumio5-onboarding[3544]: time=2025-12-30T12:59:37.662+09:00 level=INFO msg="bootstrapping state" hasInternet=true Dec 30 12:59:37 jbl-4312 volumio[3106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Received Get System Info Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Discovery: Getting this device information Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:37 jbl-4312 sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 12:59:37 jbl-4312 sudo[3638]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:37 jbl-4312 volumio[3106]: info: VolumeController:: Volume=100 Mute =false Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: MP1 GPIO: Signalled system ready via GPIO Dec 30 12:59:37 jbl-4312 volumio-remote-updater[2831]: No test mode Dec 30 12:59:37 jbl-4312 volumio-remote-updater[2831]: No alpha test mode Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Adding Inputs via Serial API Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Adding Advanced Audio Settings via Serial API Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreStateMachine::pushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioPushState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CoreCommandRouter::volumioGetState Dec 30 12:59:37 jbl-4312 volumio[3106]: info: CorePlayQueue::getTrack 0 Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Asound.conf file unchanged, so no further update is needed Dec 30 12:59:37 jbl-4312 volumio[3106]: info: Output device has changed, restarting MPD Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 30 12:59:37 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:37+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 30 12:59:38 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:38+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 30 12:59:38 jbl-4312 go-librespot[3617]: time="2025-12-30T12:59:38+09:00" level=info msg="zeroconf server listening on port 39907" Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 sudo[3647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 12:59:38 jbl-4312 sudo[3647]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:38 jbl-4312 sudo[3647]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:38 jbl-4312 sudo[3649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 12:59:38 jbl-4312 sudo[3649]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:38 jbl-4312 volumio[3106]: info: MPD Permissions set Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 30 12:59:38 jbl-4312 systemd[1]: mpd.service: Succeeded. Dec 30 12:59:38 jbl-4312 systemd[1]: Stopped Music Player Daemon. Dec 30 12:59:38 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 sudo[3656]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 12:59:38 jbl-4312 sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:38 jbl-4312 sudo[3656]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 12:59:38 jbl-4312 sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:38 jbl-4312 volumio[3106]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 12:59:38 jbl-4312 volumio[3106]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 30 12:59:38 jbl-4312 volumio[3106]: info: camilladsp service started and running in background, instance 1 Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 12:59:38 jbl-4312 volumio[3106]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 12:59:38 jbl-4312 volumio[3106]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 30 12:59:38 jbl-4312 volumio[3106]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 12:59:38 jbl-4312 volumio[3106]: info: FusionDsp loaded Dec 30 12:59:38 jbl-4312 volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 12:59:38 jbl-4312 sudo[3673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 30 12:59:38 jbl-4312 sudo[3673]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 12:59:38 jbl-4312 volumio[3106]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 30 12:59:38 jbl-4312 volumio[3106]: info: Adding Signal Path Element [object Object] Dec 30 12:59:38 jbl-4312 volumio[3106]: info: Adding fusiondspeq DSP Signal Path Element Dec 30 12:59:38 jbl-4312 volumio[3106]: info: FusionDsp - ---- installed callbackRead Dec 30 12:59:38 jbl-4312 volumio[3106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:59:38 jbl-4312 sudo[3673]: pam_unix(sudo:session): session closed for user root Dec 30 12:59:38 jbl-4312 volumio[3106]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 30 12:59:38 jbl-4312 volumio[3106]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 30 12:59:38 jbl-4312 volumio[3106]: at onErrorNT (internal/child_process.js:472:16) Dec 30 12:59:38 jbl-4312 volumio[3106]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Dec 30 12:59:38 jbl-4312 volumio[3106]: errno: -2, Dec 30 12:59:38 jbl-4312 volumio[3106]: code: 'ENOENT', Dec 30 12:59:38 jbl-4312 volumio[3106]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 12:59:38 jbl-4312 volumio[3106]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 12:59:38 jbl-4312 volumio[3106]: spawnargs: [ Dec 30 12:59:38 jbl-4312 volumio[3106]: '-p', Dec 30 12:59:38 jbl-4312 volumio[3106]: 9876, Dec 30 12:59:38 jbl-4312 volumio[3106]: '-o', Dec 30 12:59:38 jbl-4312 volumio[3106]: '/tmp/camilladsp.log', Dec 30 12:59:38 jbl-4312 volumio[3106]: '-l', Dec 30 12:59:38 jbl-4312 volumio[3106]: 'warn', Dec 30 12:59:38 jbl-4312 volumio[3106]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 30 12:59:38 jbl-4312 volumio[3106]: ] Dec 30 12:59:38 jbl-4312 volumio[3106]: } Dec 30 12:59:38 jbl-4312 volumio[3106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:59:39 jbl-4312 sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 12:58 Dec 30 12:59:39 jbl-4312 sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"