Apr 11 21:40:00 volumio upmpdcli[2257]: writing RSA key Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin bluetooth to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin multiroom to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin metavolumio to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin cd_controller to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 11 21:40:00 volumio volumio[1351]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 11 21:40:01 volumio volumio[1351]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 11 21:40:01 volumio volumio[1351]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 11 21:40:01 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 21:40:01 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 21:40:01 volumio volumio[1351]: info: Starting MyVolumio Remote Streaming Endpoints Apr 11 21:40:01 volumio volumio[1351]: info: MyVolumio login type: Token Apr 11 21:40:01 volumio volumio[1351]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 11 21:40:01 volumio volumio[1351]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 11 21:40:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 11 21:40:02 volumio volumio[1351]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 11 21:40:02 volumio volumio[1351]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 11 21:40:02 volumio volumio[1351]: info: Streaming services startup Apr 11 21:40:02 volumio volumio[1351]: info: Starting Streaming Daemon Apr 11 21:40:02 volumio sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 11 21:40:02 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:02 volumio volumio[1351]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 11 21:40:02 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 21:40:02 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Apr 11 21:40:02 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:02 volumio volumio[1351]: error: Cannot start Volumio Streaming Daemon Apr 11 21:40:02 volumio volumio[1351]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 11 21:40:02 volumio volumio[1351]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 11 21:40:02 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Apr 11 21:40:02 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Apr 11 21:40:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Apr 11 21:40:03 volumio volumio[1351]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 11 21:40:07 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 11 21:40:07 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 11 21:40:08 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:08 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:16 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 21:40:16 volumio volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 11 21:40:16 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:16 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.176+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.5.112:39606 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.191+08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.5.112:39606 @ 0xc0003560c0" latency=196.351069ms platform=PLATFORM_ANDROID version=5.260331.0 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.194+08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.5.112:39606 @ 0xc0003560c0" latency=198.100066ms timeout=10s Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.194+08:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" Apr 11 21:40:17 volumio volumio[1351]: info: Received Get System Info Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 21:40:17 volumio volumio[1351]: info: Discovery: Getting this device information Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:17 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.200+08:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" name=Volumio Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.202+08:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" language=zh Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.204+08:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" timezone=Asia/Shanghai Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.205+08:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" available=true connected=true macAddress=7c:d3:0a:34:2c:7b ip4Address=192.168.5.224/24 ip6Address= Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.206+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.207+08:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" setupComplete=true Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: amixer -c 7 info | grep "Loopback" Apr 11 21:40:17 volumio volumio[1351]: Card sysdefault:7 'Loopback'/'Loopback 1' Apr 11 21:40:17 volumio volumio[1351]: Mixer name : 'Loopback Mixer' Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: amixer -c 5 info | grep "A" Apr 11 21:40:17 volumio volumio[1351]: Card sysdefault:5 'A'/'EROS A at usb-0000:00:12.0-1.4, high speed' Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 0 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 7 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 3 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Apr 11 21:40:17 volumio volumio[1351]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 21:40:17 volumio volumio[1351]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 21:40:17 volumio volumio[1351]: amixer -c 5 info | grep "A" Apr 11 21:40:17 volumio volumio[1351]: Card sysdefault:5 'A'/'EROS A at usb-0000:00:12.0-1.4, high speed' Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.320+08:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" selectedOutputId=5 Apr 11 21:40:17 volumio volumio[1351]: info: Received Get System Info Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 21:40:17 volumio volumio[1351]: info: Discovery: Getting this device information Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:17 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.496+08:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" currentVersion=4.103 latestVersion=4.103 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.496+08:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" status=UPDATE_STATUS_NONE progress=0 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.500+08:00 level=INFO msg="emitting user changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" userId= Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.502+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.503+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.503+08:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.503+08:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" providers=3 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.506+08:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" plugins=0 Apr 11 21:40:17 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:17 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.511+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" state=STATUS_STOPPED positionMs=0 volume=100 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.512+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" id=cue://NAS/MusicHD/1998-00-最动听的...林子祥[华纳港版][WAV]/林子祥.-.[最动听的...林子祥CD1](1998)[WAV].cue@0 title=莫再悲 Apr 11 21:40:17 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:17.692+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.112:39606 @ 0xc0003560c0" latency=197.710727ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 11 21:40:18 volumio sudo[2362]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 21:40:18 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:18 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:18 volumio sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 21:40:18 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:18 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:18 volumio volumio[1351]: verbose: New Socket.io Connection to 192.168.5.224 from 192.168.5.112 UA: Mozilla/5.0 (Linux; Android 16; V2366HA Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 11 21:40:18 volumio sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 21:40:18 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:18 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:18 volumio sudo[2370]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 21:40:18 volumio sudo[2370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:18 volumio sudo[2370]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:18 volumio volumio[1351]: verbose: New Socket.io Connection to 192.168.5.224 from 192.168.5.112 UA: Mozilla/5.0 (Linux; Android 16; V2366HA Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:18 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 21:40:18 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:18 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:18 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetQueue Apr 11 21:40:18 volumio volumio[1351]: info: CoreStateMachine::getQueue Apr 11 21:40:18 volumio volumio[1351]: info: CorePlayQueue::getQueue Apr 11 21:40:18 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 21:40:18 volumio volumio[1351]: info: Received Get System Info Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 21:40:18 volumio volumio[1351]: info: Discovery: Getting this device information Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:18 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:18 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 21:40:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 21:40:19 volumio volumio-remote-updater[832]: Test mode disabled Apr 11 21:40:19 volumio volumio-remote-updater[832]: Alpha mode disabled Apr 11 21:40:19 volumio volumio-remote-updater[832]: Alpha legacy test mode disabled Apr 11 21:40:19 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 11 21:40:19 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 21:40:20 volumio volumio[1351]: info: Received Get System Info Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 21:40:20 volumio volumio[1351]: info: Discovery: Getting this device information Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:20 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 21:40:20 volumio volumio[1351]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

  • Fix for missing PWM backlight module
  • Fix for crash loop on Onboarding plugin

NEW ADDITIONS

  • Bump RPi/CM4 kernel to 6.12.74

","title":"Update v4.119","updateavailable":true} Apr 11 21:40:20 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 21:40:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 21:40:22 volumio volumio[1351]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 11 21:40:22 volumio volumio[1351]: info: Completed starting MyVolumio Plugin Apr 11 21:40:22 volumio volumio[1351]: [Metrics] CommandRouter: 45s 495.86ms Apr 11 21:40:22 volumio volumio[1351]: info: CoreCommandRouter::volumiosetStartupVolume Apr 11 21:40:22 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 21:40:22 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 21:40:22 volumio volumio[1351]: info: CoreCommandRouter::Close All Modals sent Apr 11 21:40:22 volumio volumio[1351]: info: CoreCommandRouter::Close All Modals sent Apr 11 21:40:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 11 21:40:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 21:40:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 11 21:40:26 volumio volumio[1351]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/HANA%E8%8F%8A%E6%A2%93%E4%B9%94/0a4fdf14-01d4-4cdc-9183-91ad7ffc3a39.jpg' Apr 11 21:40:28 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 21:40:28 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:28 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:29 volumio volumio[1351]: info: BOOT COMPLETED Apr 11 21:40:30 volumio volumio[1351]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Soler/3b319728-e366-4f31-9c3f-707059a739b5.jpg' Apr 11 21:40:32 volumio volumio[1351]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 11 21:40:32 volumio volumio[1351]: info: Retrying Login Due to Network Error Apr 11 21:40:33 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:33 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 21:40:33 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 11 21:40:33 volumio sudo[2438]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 11 21:40:33 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 11 21:40:33 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 11 21:40:33 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 11 21:40:33 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 11 21:40:33 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 11 21:40:33 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 21:40:33 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 21:40:33 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:33 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:33 volumio sudo[2468]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 21:40:33 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 21:40:38 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root Apr 11 21:40:38 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:38.704+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.112:39606 @ 0xc0003560c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Apr 11 21:40:38 volumio volumio[1351]: info: Received Get System Info Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 21:40:38 volumio volumio[1351]: info: Discovery: Getting this device information Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:38 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 21:40:38 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:38 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:38 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:38 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:39 volumio volumio5-onboarding[2051]: time=2026-04-11T21:40:39.715+08:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 11 21:40:42 volumio volumio[1351]: info: MyVolumio login type: Token Apr 11 21:40:48 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Apr 11 21:40:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:49 volumio volumio[1351]: info: UPDATER: Scheduling automatic update Apr 11 21:40:49 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Apr 11 21:40:49 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Apr 11 21:40:49 volumio volumio[1351]: info: UPDATER: Auto update will take place at: Sun Apr 12 2026 03:00:34 GMT+0800 (China Standard Time) Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: info: Retrieving Cloud Streaming UI Apr 11 21:40:55 volumio volumio[1351]: info: Getting Tidal Cloud Configuration Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: info: Getting Qobuz Cloud Configuration Apr 11 21:40:55 volumio volumio[1351]: info: Asking plugin for UI Config Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: info: Getting Spotify Cloud Configuration Apr 11 21:40:55 volumio volumio[1351]: info: Asking plugin for UI Config Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: info: Saving Spotify Acccount Apr 11 21:40:55 volumio volumio[1351]: info: Got Tidal Cloud Configuration Apr 11 21:40:55 volumio volumio[1351]: info: Got it Apr 11 21:40:55 volumio volumio[1351]: info: Got it Apr 11 21:40:55 volumio volumio[1351]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 21:40:55 volumio volumio[1351]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 21:40:55 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 11 21:40:58 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 11 21:40:58 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 11 21:40:58 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Apr 11 21:40:58 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Apr 11 21:40:58 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:58 volumio volumio[1351]: info: Listing playlists Apr 11 21:40:59 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 21:40:59 volumio volumio[1351]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L hualong.local. -m SMB3_11 Apr 11 21:41:03 volumio volumio[1351]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 21:41:03 volumio volumio[1351]: Error: dns service error: unknown Apr 11 21:41:03 volumio volumio[1351]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 11 21:41:03 volumio volumio[1351]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 11 21:41:03 volumio volumio[1351]: errorCode: -65537 Apr 11 21:41:03 volumio volumio[1351]: } Apr 11 21:41:03 volumio volumio[1351]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 21:41:03 volumio sudo[2571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 21:40' Apr 11 21:41:03 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"