-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Tue 2025-12-30 13:07:15 JST. -- Dec 30 13:07:04 jbl-4312 systemd-timedated[3374]: Changed local time to Tue Dec 30 13:07:04 2025 Dec 30 13:07:04 jbl-4312 sudo[3372]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:04 jbl-4312 volumio-time-update[2851]: volumio-time-update-util: System time updated successfully. Dec 30 13:07:04 jbl-4312 systemd[1]: Started Volumio Time Update Utility. Dec 30 13:07:04 jbl-4312 systemd[1]: Reached target Multi-User System. Dec 30 13:07:04 jbl-4312 systemd[1]: Reached target Graphical Interface. Dec 30 13:07:04 jbl-4312 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 30 13:07:04 jbl-4312 systemd[1]: Starting Daily man-db regeneration... Dec 30 13:07:04 jbl-4312 systemd[1]: Starting Daily apt download activities... Dec 30 13:07:04 jbl-4312 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 30 13:07:04 jbl-4312 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 30 13:07:04 jbl-4312 systemd[1]: man-db.service: Succeeded. Dec 30 13:07:04 jbl-4312 systemd[1]: Started Daily man-db regeneration. Dec 30 13:07:04 jbl-4312 ntpd[3321]: Soliciting pool server 162.159.200.123 Dec 30 13:07:04 jbl-4312 ntpd[3321]: Soliciting pool server 153.127.38.46 Dec 30 13:07:04 jbl-4312 ntpd[3321]: Soliciting pool server 172.105.192.74 Dec 30 13:07:04 jbl-4312 volumio[3111]: info: Loading plugin "qobuz"... Dec 30 13:07:05 jbl-4312 ntpd[3321]: Soliciting pool server 129.250.35.251 Dec 30 13:07:05 jbl-4312 ntpd[3321]: Soliciting pool server 129.250.35.250 Dec 30 13:07:05 jbl-4312 ntpd[3321]: Soliciting pool server 142.91.108.61 Dec 30 13:07:06 jbl-4312 ntpd[3321]: Soliciting pool server 43.229.16.149 Dec 30 13:07:06 jbl-4312 ntpd[3321]: Soliciting pool server 85.121.50.82 Dec 30 13:07:06 jbl-4312 ntpd[3321]: Soliciting pool server 162.159.200.1 Dec 30 13:07:06 jbl-4312 systemd[1]: apt-daily.service: Succeeded. Dec 30 13:07:06 jbl-4312 systemd[1]: Started Daily apt download activities. Dec 30 13:07:06 jbl-4312 systemd[1]: Starting Daily apt upgrade and clean activities... Dec 30 13:07:06 jbl-4312 volumio-remote-updater[2836]: [2025-12-30 13:07:06] [connect] Successful connection Dec 30 13:07:06 jbl-4312 volumio[3111]: info: Plugin smart_inputs is not enabled Dec 30 13:07:06 jbl-4312 volumio[3111]: info: Loading plugin "tidal"... Dec 30 13:07:07 jbl-4312 ntpd[3321]: Soliciting pool server 45.77.20.103 Dec 30 13:07:07 jbl-4312 ntpd[3321]: Soliciting pool server 103.131.151.20 Dec 30 13:07:07 jbl-4312 ntpd[3321]: Soliciting pool server 5.104.87.198 Dec 30 13:07:08 jbl-4312 systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 30 13:07:08 jbl-4312 systemd[1]: Started Daily apt upgrade and clean activities. Dec 30 13:07:08 jbl-4312 systemd[1]: Startup finished in 12.817s (kernel) + 24.570s (userspace) = 37.388s. Dec 30 13:07:08 jbl-4312 ntpd[3321]: Soliciting pool server 2001:678:8::123 Dec 30 13:07:08 jbl-4312 ntpd[3321]: Soliciting pool server 43.167.237.61 Dec 30 13:07:08 jbl-4312 volumio[3111]: info: Loading plugin "rivopluscontrol"... Dec 30 13:07:08 jbl-4312 volumio[3111]: info: Adding this device properties Dec 30 13:07:08 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 30 13:07:08 jbl-4312 volumio[3111]: info: Setting Additional Device Volumio Properties: [object Object] Dec 30 13:07:08 jbl-4312 volumio[3111]: info: Loading plugin "updater_comm"... Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Loading plugin "cec_controller"... Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Applying required configuration parameters for plugin cec_controller Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Plugin mpdemulation is not enabled Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Loading plugin "rest_api"... Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Loading plugin "websocket"... Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Starting Socket.io Server version 2.3.0 Dec 30 13:07:09 jbl-4312 volumio[3111]: info: Loading plugin "fusiondsp"... Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Applying required configuration parameters for plugin fusiondsp Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Loading i18n strings for locale ja Dec 30 13:07:10 jbl-4312 volumio[3111]: Updating browse sources language Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::initPlayerControls Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: Express server listening on port 3000 Dec 30 13:07:10 jbl-4312 volumio[3111]: [Metrics] WebUI: 18s 728.97ms Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::resetVolumioState Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::getcurrentVolume Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 13:07:10 jbl-4312 volumio[3111]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Completed loading Core Plugins Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Preparing to generate the ALSA configuration file Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Cannot read play queue from file Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Volumio Network Manager: Network status updated: 0 Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 30 13:07:10 jbl-4312 volumio[3111]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 30 13:07:10 jbl-4312 volumio[3111]: info: Reading ALSA contributions from plugins. Dec 30 13:07:10 jbl-4312 volumio-remote-updater[2836]: [2025-12-30 13:07:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767067626 101 Dec 30 13:07:10 jbl-4312 volumio[3111]: 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 13:07:10 jbl-4312 volumio[3111]: info: VolumeController:: Volume=100 Mute =false Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::updateTrackBlock Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrackBlock Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::setRepeat null single undefined Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:10 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreStateMachine::setRandom null Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Discovery: A device disappeared from network Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Asound.conf file unchanged, so no further update is needed Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Output device has changed, restarting MPD Dec 30 13:07:11 jbl-4312 sudo[3534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 13:07:11 jbl-4312 volumio[3111]: info: ___________ START PLUGINS ___________ Dec 30 13:07:11 jbl-4312 sudo[3534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 sudo[3534]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:11 jbl-4312 sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 13:07:11 jbl-4312 sudo[3536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 volumio[3111]: info: ControllerMpd::onStart: Initializing MPD Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Creating MPD Configuration file Dec 30 13:07:11 jbl-4312 sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Dec 30 13:07:11 jbl-4312 sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 systemd[1]: Listening on mpd.socket. Dec 30 13:07:11 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 13:07:11 jbl-4312 ntpd[3321]: receive: Unexpected origin timestamp 0xecfdce6f.26620b47 does not match aorg 0000000000.00000000 from server@199.189.156.6 xmt 0xecfdce6f.c5b9989b Dec 30 13:07:11 jbl-4312 ntpd[3321]: receive: Unexpected origin timestamp 0xecfdce6f.264eea0d does not match aorg 0000000000.00000000 from server@43.167.237.61 xmt 0xecfdce6f.c5ec0bfa Dec 30 13:07:11 jbl-4312 ntpd[3321]: receive: Unexpected origin timestamp 0xecfdce6f.265fc5ce does not match aorg 0000000000.00000000 from server@139.64.134.57 xmt 0xecfdce6f.c64904fa Dec 30 13:07:11 jbl-4312 sudo[3542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 13:07:11 jbl-4312 sudo[3542]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 13:07:11 jbl-4312 volumio[3111]: info: [1767067631791] CoreMusicLibrary::Adding element Last_100 Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 13:07:11 jbl-4312 ntpd[3321]: receive: Unexpected origin timestamp 0xecfdce6f.265d66a8 does not match aorg 0000000000.00000000 from server@153.127.38.46 xmt 0xecfdce6f.c7fb7ac4 Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Streaming services startup Dec 30 13:07:11 jbl-4312 sudo[3542]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Starting Streaming Daemon Dec 30 13:07:11 jbl-4312 systemd[1]: Started Volumio5 Onboarding Server. Dec 30 13:07:11 jbl-4312 sudo[3540]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:11 jbl-4312 sudo[3547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 13:07:11 jbl-4312 sudo[3547]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 sudo[3551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 13:07:11 jbl-4312 sudo[3551]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:11 jbl-4312 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 30 13:07:11 jbl-4312 systemd[1]: mpd.service: Succeeded. Dec 30 13:07:11 jbl-4312 systemd[1]: Stopped Music Player Daemon. Dec 30 13:07:11 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 13:07:11 jbl-4312 volumio[3111]: info: [1767067631931] CoreMusicLibrary::Adding element Webradio Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 13:07:11 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 13:07:11 jbl-4312 volumio[3111]: info: Initializing BBC Radios Dec 30 13:07:11 jbl-4312 sudo[3551]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Creating Spotify config file Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:12 jbl-4312 volumio[3111]: error: Hi Res Audio Failed Login: Missing Login Data Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 13:07:12 jbl-4312 sudo[3556]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Initializing Serial Communication on port /dev/ttyS3 Dec 30 13:07:12 jbl-4312 sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Touch Event Listener Process Starting Dec 30 13:07:12 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Dec 30 13:07:12 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Dec 30 13:07:12 jbl-4312 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Dec 30 13:07:12 jbl-4312 sudo[3556]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 13:07:12 jbl-4312 sudo[3556]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Dec 30 13:07:12 jbl-4312 sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3576]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 sudo[3586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Dec 30 13:07:12 jbl-4312 sudo[3586]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3586]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Dec 30 13:07:12 jbl-4312 sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3589]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:12.332+09:00 level=INFO msg="running volumio5-device-gateway" version=5bca5789+CHANGES buildDate=2025-12-11T14:07:23Z Dec 30 13:07:12 jbl-4312 sudo[3592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Dec 30 13:07:12 jbl-4312 sudo[3592]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3592]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 sudo[3595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Dec 30 13:07:12 jbl-4312 sudo[3595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3595]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Apply VIM3L Onboard LEDs Settings Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Turning On Onboard LEDs Dec 30 13:07:12 jbl-4312 sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 13:07:12 jbl-4312 sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Starting CEC Event listener Dec 30 13:07:12 jbl-4312 sudo[3599]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Loading i18n strings for locale ja Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:12 jbl-4312 volumio[3111]: info: FusionDsp - mixtype--------------------- Hardware Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Volumio Calling Home Dec 30 13:07:12 jbl-4312 sudo[3603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 13:07:12 jbl-4312 sudo[3603]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:12 jbl-4312 sudo[3603]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Preparing to generate the ALSA configuration file Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Stopping AccessToken refresher cron for QOBUZ Dec 30 13:07:12 jbl-4312 volumio[3111]: info: AccessToken refresher cron started for QOBUZ Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding TIDAL REST API Endpoints Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding QOBUZ REST API Endpoints Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Discovery: adding 477e6b25-e4ef-4cfb-b828-303619ebc483 Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Discovery: Found device JBL 4312 Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Serial port opened successfully Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Sending serial start messages Dec 30 13:07:12 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Reporting MCU Network Status: 0 Dec 30 13:07:12 jbl-4312 volumio[3111]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 30 13:07:12 jbl-4312 volumio[3111]: info: Reading ALSA contributions from plugins. Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Touch Event Listener Process Closed Dec 30 13:07:13 jbl-4312 volumio[3111]: error: Cannot start Volumio Streaming Daemon Dec 30 13:07:13 jbl-4312 volumio[3111]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 13:07:13 jbl-4312 volumio[3111]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MPD Permissions set Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MPD Permissions set Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting Device Volume Override Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 13:07:13 jbl-4312 volumio[3111]: 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 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Disabling external Volume Control Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:13 jbl-4312 volumio[3111]: 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 13:07:13 jbl-4312 volumio[3111]: info: VolumeController:: Volume=100 Mute =false Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Spotify config file written Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.217+09:00 level=WARN msg="server is not set, cannot notify peers" component=state Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.218+09:00 level=WARN msg="server is not set, cannot notify peers" component=state Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting Additional System Software info: Hardware Revision: 1.0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting HW Firmware info: undefined Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.247+09:00 level=INFO msg="listening for BLE messages" address=B8:13:32:E4:3F:CF%00 Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.248+09:00 level=INFO msg="listening for WebSocket messages" address=[::]:7331 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting HW Version info: 1.0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting HW Firmware info: 0.3.0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Setting HW Version info: 1.0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MCU Signalled Headphone Mode Disabled Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MCU Signalled Sleep Mode Disabled Dec 30 13:07:13 jbl-4312 sudo[3619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 13:07:13 jbl-4312 sudo[3619]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Enabling Advanced system settings configuration Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MCU Signalled Auto Boot Mode On Power Disabled Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Discovery: this is already registered, 477e6b25-e4ef-4cfb-b828-303619ebc483 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Discovery: Found device JBL 4312 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 30 13:07:13 jbl-4312 sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:13 jbl-4312 systemd[1]: Started go-librespot Daemon. Dec 30 13:07:13 jbl-4312 sudo[3619]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:13 jbl-4312 sudo[3622]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:13 jbl-4312 go-librespot[3624]: go-librespot daemon starting... Dec 30 13:07:13 jbl-4312 volumio[3111]: 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 13:07:13 jbl-4312 volumio[3111]: info: Received Get System Info Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Discovery: Getting this device information Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 13:07:13 jbl-4312 volumio[3111]: 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 13:07:13 jbl-4312 volumio[3111]: info: Volumio called home Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 13:07:13 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:13+09:00" level=info msg="running go-librespot 0.4.0" Dec 30 13:07:13 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:13+09:00" level=debug msg="app state loaded" Dec 30 13:07:13 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:13+09:00" level=debug msg="stored credentials not found" Dec 30 13:07:13 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:13+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: No need to fix Spotify hosts Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:13 jbl-4312 volumio[3111]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.701+09:00 level=INFO msg="BLE descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName="JBL 4312" deviceModel=0x3503360 Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.704+09:00 level=INFO msg="mDNS descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName="JBL 4312" deviceModel=0x3503360 Dec 30 13:07:13 jbl-4312 volumio5-onboarding[3548]: time=2025-12-30T13:07:13.715+09:00 level=INFO msg="bootstrapping state" hasInternet=true Dec 30 13:07:13 jbl-4312 volumio[3111]: 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 13:07:13 jbl-4312 volumio[3111]: info: Received Get System Info Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Discovery: Getting this device information Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:13 jbl-4312 sudo[3646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 13:07:13 jbl-4312 sudo[3646]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:13 jbl-4312 sudo[3646]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:13 jbl-4312 volumio[3111]: info: VolumeController:: Volume=100 Mute =false Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: MP1 GPIO: Signalled system ready via GPIO Dec 30 13:07:13 jbl-4312 volumio-remote-updater[2836]: No test mode Dec 30 13:07:13 jbl-4312 volumio-remote-updater[2836]: No alpha test mode Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Adding Inputs via Serial API Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Adding Advanced Audio Settings via Serial API Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 30 13:07:13 jbl-4312 volumio[3111]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreStateMachine::pushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioPushState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:13 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::volumioGetState Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CorePlayQueue::getTrack 0 Dec 30 13:07:14 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:14+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 30 13:07:14 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:14+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 30 13:07:14 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:14+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 30 13:07:14 jbl-4312 volumio[3111]: info: Asound.conf file unchanged, so no further update is needed Dec 30 13:07:14 jbl-4312 volumio[3111]: info: Output device has changed, restarting MPD Dec 30 13:07:14 jbl-4312 go-librespot[3624]: time="2025-12-30T13:07:14+09:00" level=info msg="zeroconf server listening on port 35929" Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 13:07:14 jbl-4312 sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:14 jbl-4312 sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:14 jbl-4312 sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 13:07:14 jbl-4312 sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:14 jbl-4312 volumio[3111]: info: MPD Permissions set Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 30 13:07:14 jbl-4312 systemd[1]: mpd.service: Succeeded. Dec 30 13:07:14 jbl-4312 systemd[1]: Stopped Music Player Daemon. Dec 30 13:07:14 jbl-4312 systemd[1]: Starting Music Player Daemon... Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 sudo[3665]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 13:07:14 jbl-4312 sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:14 jbl-4312 sudo[3665]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 13:07:14 jbl-4312 sudo[3665]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:14 jbl-4312 volumio[3111]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 13:07:14 jbl-4312 volumio[3111]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 30 13:07:14 jbl-4312 volumio[3111]: info: camilladsp service started and running in background, instance 1 Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 13:07:14 jbl-4312 volumio[3111]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 13:07:14 jbl-4312 volumio[3111]: 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 13:07:14 jbl-4312 volumio[3111]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 13:07:14 jbl-4312 volumio[3111]: info: FusionDsp loaded Dec 30 13:07:14 jbl-4312 volumio[3111]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 13:07:14 jbl-4312 sudo[3683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 30 13:07:14 jbl-4312 sudo[3683]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 13:07:14 jbl-4312 volumio[3111]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 30 13:07:14 jbl-4312 volumio[3111]: info: Adding Signal Path Element [object Object] Dec 30 13:07:14 jbl-4312 volumio[3111]: info: Adding fusiondspeq DSP Signal Path Element Dec 30 13:07:14 jbl-4312 volumio[3111]: info: FusionDsp - ---- installed callbackRead Dec 30 13:07:14 jbl-4312 volumio[3111]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 13:07:14 jbl-4312 sudo[3683]: pam_unix(sudo:session): session closed for user root Dec 30 13:07:14 jbl-4312 volumio[3111]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 30 13:07:14 jbl-4312 volumio[3111]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 30 13:07:14 jbl-4312 volumio[3111]: at onErrorNT (internal/child_process.js:472:16) Dec 30 13:07:14 jbl-4312 volumio[3111]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Dec 30 13:07:14 jbl-4312 volumio[3111]: errno: -2, Dec 30 13:07:14 jbl-4312 volumio[3111]: code: 'ENOENT', Dec 30 13:07:14 jbl-4312 volumio[3111]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 13:07:14 jbl-4312 volumio[3111]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 13:07:14 jbl-4312 volumio[3111]: spawnargs: [ Dec 30 13:07:14 jbl-4312 volumio[3111]: '-p', Dec 30 13:07:14 jbl-4312 volumio[3111]: 9876, Dec 30 13:07:14 jbl-4312 volumio[3111]: '-o', Dec 30 13:07:14 jbl-4312 volumio[3111]: '/tmp/camilladsp.log', Dec 30 13:07:14 jbl-4312 volumio[3111]: '-l', Dec 30 13:07:14 jbl-4312 volumio[3111]: 'warn', Dec 30 13:07:14 jbl-4312 volumio[3111]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 30 13:07:14 jbl-4312 volumio[3111]: ] Dec 30 13:07:14 jbl-4312 volumio[3111]: } Dec 30 13:07:14 jbl-4312 volumio[3111]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 13:07:15 jbl-4312 sudo[3694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 13:06 Dec 30 13:07:15 jbl-4312 sudo[3694]: 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"