Aug 28 21:43:00 volumio volumio[971]: info: Enabling plugin audio_keepalive
Aug 28 21:43:00 volumio volumio[971]: info: Loading plugin "audio_keepalive"...
Aug 28 21:43:01 volumio volumio[971]: info: Preparing to generate the ALSA configuration file
Aug 28 21:43:01 volumio volumio[971]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 28 21:43:01 volumio volumio[971]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Aug 28 21:43:01 volumio volumio[971]: info: Reading ALSA contributions from plugins.
Aug 28 21:43:01 volumio volumio[971]: info: Asound.conf file unchanged, so no further update is needed
Aug 28 21:43:01 volumio volumio[971]: info: Output device has changed, restarting MPD
Aug 28 21:43:01 volumio volumio[971]: info: Output device has changed, restarting Shairport Sync
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 28 21:43:01 volumio sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 28 21:43:01 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:01 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:01 volumio volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 28 21:43:01 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:01 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:01 volumio volumio[971]: info: PLUGIN START: audio_keepalive
Aug 28 21:43:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:01 volumio volumio[971]: info: MPD Permissions set
Aug 28 21:43:01 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:01 volumio systemd[1]: mpd.service: Consumed 4.359s CPU time.
Aug 28 21:43:01 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio sudo[2302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audio-keepalive.service
Aug 28 21:43:01 volumio sudo[2302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 28 21:43:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:01 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms
Aug 28 21:43:01 volumio volumio[971]: info: camilladsp respawn in 100 ms (attempt 1/10)
Aug 28 21:43:01 volumio sudo[2302]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:01 volumio volumio[971]: info: AudioKeepalive: systemctl stop ok
Aug 28 21:43:01 volumio volumio[971]: info: Done.
Aug 28 21:43:01 volumio sudo[2310]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:01 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:01 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:01 volumio volumio[971]: info: Starting Shairport Sync
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:01 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:01 volumio volumio[971]: info: CoreStateMachine::stPlaybackTimer
Aug 28 21:43:01 volumio volumio[971]: info: CoreStateMachine::servicePause
Aug 28 21:43:01 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:01 volumio volumio[971]: info: CoreCommandRouter::servicePause
Aug 28 21:43:01 volumio volumio[971]: info: [1787920981868] ControllerWebradio::pause
Aug 28 21:43:01 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand pause
Aug 28 21:43:01 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:01 volumio volumio[971]: info: mpdhttpout ---Configuration successfully added to mpd.conf.
Aug 28 21:43:01 volumio sudo[2317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 28 21:43:01 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 28 21:43:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 28 21:43:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 28 21:43:01 volumio systemd[1]: shairport-sync.service: Consumed 2.037s CPU time.
Aug 28 21:43:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 28 21:43:01 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:01 volumio volumio[971]: info: Shairport-Sync Started
Aug 28 21:43:03 volumio mpd[2312]: 2026-08-28T21:43:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:03 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:03 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:03 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:03 volumio volumio[971]: error: FusionDsp - Monitor WebSocket error: [object Object]
Aug 28 21:43:03 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms
Aug 28 21:43:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:04 volumio sudo[2351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:04 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:04 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:04 volumio systemd[1]: mpd.service: Consumed 1.419s CPU time.
Aug 28 21:43:04 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:04 volumio sudo[2354]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:04 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:04 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:04 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:43:05 volumio mpd[2356]: 2026-08-28T21:43:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:05 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:05 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:05 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:07 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:43:07 volumio volumio[971]: error: FusionDsp - Monitor WebSocket error: [object Object]
Aug 28 21:43:07 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms
Aug 28 21:43:10 volumio volumio[971]: info: CALLMETHOD: audio_interface audio_keepalive saveSettings [object Object]
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: audio_keepalive , saveSettings
Aug 28 21:43:10 volumio volumio[971]: info: AudioKeepalive: ALSA contribution file written
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Aug 28 21:43:10 volumio volumio[971]: info: Preparing to generate the ALSA configuration file
Aug 28 21:43:10 volumio volumio[971]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Aug 28 21:43:10 volumio volumio[971]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 28 21:43:10 volumio volumio[971]: info: The plugin audio_keepalive has an ALSA contribution file keepaliveProxy.keepaliveProxyOut.-1.conf
Aug 28 21:43:10 volumio volumio[971]: info: Reading ALSA contributions from plugins.
Aug 28 21:43:10 volumio volumio[971]: info: Asound.conf file written
Aug 28 21:43:10 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Aug 28 21:43:10 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:10 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:10 volumio volumio[971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Aug 28 21:43:10 volumio volumio[971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Aug 28 21:43:10 volumio volumio[971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Aug 28 21:43:10 volumio volumio[971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2
Aug 28 21:43:10 volumio volumio[971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2
Aug 28 21:43:10 volumio volumio[971]: info: Output device has changed, restarting MPD
Aug 28 21:43:10 volumio volumio[971]: info: Output device has changed, restarting Shairport Sync
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 28 21:43:10 volumio sudo[2379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 28 21:43:10 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:10 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:10 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:10 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:10 volumio volumio[971]: info: MPD Permissions set
Aug 28 21:43:10 volumio sudo[2388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart audio-keepalive.service
Aug 28 21:43:10 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:10 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:10 volumio systemd[1]: mpd.service: Consumed 1.317s CPU time.
Aug 28 21:43:10 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 28 21:43:10 volumio volumio[971]: info: Starting Shairport Sync
Aug 28 21:43:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:10 volumio volumio[971]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:10 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:10 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:10 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:10 volumio systemd[1]: Started audio-keepalive.service - Volumio Audio Keepalive.
Aug 28 21:43:10 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:10 volumio volumio[971]: info: AudioKeepalive: systemctl restart ok
Aug 28 21:43:10 volumio audio-keepalive[2400]: audio-keepalive: listening on /run/audio-keepalive/ctl.sock
Aug 28 21:43:10 volumio audio-keepalive[2400]: audio-keepalive: no test sentinel — idle noise -100 dB
Aug 28 21:43:10 volumio audio-keepalive[2400]: audio-keepalive: starting — continuous mix, no volume control
Aug 28 21:43:10 volumio audio-keepalive[2400]: audio-keepalive: opened keepaliveProxyOut rate=48000 ch=2 fmt=S16_LE period=1024 buffer=4096
Aug 28 21:43:10 volumio sudo[2401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 28 21:43:10 volumio sudo[2398]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:10 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:10 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:10 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 28 21:43:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 28 21:43:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 28 21:43:11 volumio systemd[1]: shairport-sync.service: Consumed 1.476s CPU time.
Aug 28 21:43:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 28 21:43:11 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:11 volumio volumio[971]: info: Shairport-Sync Started
Aug 28 21:43:12 volumio mpd[2404]: 2026-08-28T21:43:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:12 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:12 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:12 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:12 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:13 volumio sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:13 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:13 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:13 volumio systemd[1]: mpd.service: Consumed 1.408s CPU time.
Aug 28 21:43:13 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:13 volumio sudo[2434]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:13 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:13 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:14 volumio mpd[2442]: 2026-08-28T21:43:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:14 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:14 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:14 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:15 volumio volumio[971]: error: FusionDsp - Monitor WebSocket error: [object Object]
Aug 28 21:43:15 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms
Aug 28 21:43:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 28 21:43:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 28 21:43:19 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 28 21:43:24 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:43:26 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:43:30 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 28 21:43:30 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 28 21:43:31 volumio volumio[971]: error: FusionDsp - Monitor WebSocket error: [object Object]
Aug 28 21:43:31 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Aug 28 21:43:33 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:43:35 volumio volumio[971]: info: CALLMETHOD: audio_interface mpdoutput ehttpstream
Aug 28 21:43:35 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , ehttpstream
Aug 28 21:43:35 volumio volumio[971]: info: mpdhttpout ---TRUE
Aug 28 21:43:35 volumio volumio[971]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Aug 28 21:43:35 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:35 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:35 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:35 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:36 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:43:37 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:37 volumio sudo[2523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:37 volumio sudo[2523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:37 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:37 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:37 volumio systemd[1]: mpd.service: Consumed 1.341s CPU time.
Aug 28 21:43:37 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:37 volumio sudo[2526]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:37 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:37 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:39 volumio mpd[2528]: 2026-08-28T21:43:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:39 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:39 volumio sudo[2523]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:39 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:42 volumio volumio[971]: info: CALLMETHOD: audio_interface mpdoutput updateConfig [object Object]
Aug 28 21:43:42 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfig
Aug 28 21:43:42 volumio volumio[971]: info: mpdhttpout ---Received data for http: {"bitrate":{"label":"320","value":"320"},"buffer_time":"5000","encoder":{"label":"lame","value":"lame"},"format":{"label":"44100:16:2","value":"44100:16:2"},"httpadd":false,"max_clients":"5","outburst_time":"2000","portn":"8000","servername":"localhost"}
Aug 28 21:43:42 volumio volumio[971]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Aug 28 21:43:42 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:42 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:42 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:42 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:43 volumio volumio[971]: info: CALLMETHOD: audio_interface mpdoutput eicestream
Aug 28 21:43:43 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , eicestream
Aug 28 21:43:43 volumio volumio[971]: info: mpdhttpout ---TRUE
Aug 28 21:43:43 volumio volumio[971]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Aug 28 21:43:43 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:43 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:43 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:43 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:44 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:43:44 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:43:44 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:44 volumio sudo[2558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:44 volumio sudo[2558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:44 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:44 volumio systemd[1]: mpd.service: Consumed 1.408s CPU time.
Aug 28 21:43:44 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:45 volumio sudo[2562]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:45 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:45 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:45 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:45 volumio sudo[2567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:45 volumio sudo[2567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:45 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:45 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:45 volumio sudo[2570]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:45 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:45 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:47 volumio mpd[2572]: 2026-08-28T21:43:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:47 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:47 volumio sudo[2567]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:47 volumio sudo[2558]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:47 volumio volumio[971]: error: MPD error: The expression evaluated to a falsy value:
Aug 28 21:43:47 volumio volumio[971]: assert.ok(self.idling)
Aug 28 21:43:47 volumio volumio[971]: error: The expression evaluated to a falsy value:
Aug 28 21:43:47 volumio volumio[971]: assert.ok(self.idling)
Aug 28 21:43:47 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:47 volumio volumio[971]: info: CALLMETHOD: audio_interface mpdoutput updateConfigIce [object Object]
Aug 28 21:43:47 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfigIce
Aug 28 21:43:47 volumio volumio[971]: info: mpdhttpout ---Received data for icecast: {"iceadd":false,"icebitrate":{"label":"320","value":"320"},"icedescription":"My music stream","iceencoder":{"label":"vorbis","value":"vorbis"},"iceformat":{"label":"44100:16:2","value":"44100:16:2"},"icegenre":"various","icemountp":"/volumio.ogg","icepassword":"hackme","iceprotocol":{"label":"icecast2","value":"icecast2"},"icepublic":{"label":"no","value":"no"},"icepublishport":"8015","iceservername":"ice.example.com","icestreamname":"My Ice stream","iceurl":"","iceuser":"source"}
Aug 28 21:43:47 volumio volumio[971]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Aug 28 21:43:47 volumio volumio[971]: info: CoreCommandRouter::volumioGetState
Aug 28 21:43:47 volumio volumio[971]: info: CoreCommandRouter::volumioPause
Aug 28 21:43:47 volumio volumio[971]: info: CoreStateMachine::pause
Aug 28 21:43:47 volumio volumio[971]: info: mpdhttpout --- Volumio set on pause
Aug 28 21:43:49 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Aug 28 21:43:49 volumio sudo[2582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 28 21:43:49 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:43:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 28 21:43:49 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 28 21:43:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 28 21:43:49 volumio systemd[1]: mpd.service: Consumed 1.342s CPU time.
Aug 28 21:43:49 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 28 21:43:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 28 21:43:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 28 21:43:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 28 21:43:49 volumio sudo[2585]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 28 21:43:49 volumio sudo[2585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:43:49 volumio sudo[2585]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:50 volumio mpd[2587]: 2026-08-28T21:43:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 28 21:43:50 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 28 21:43:50 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root
Aug 28 21:43:50 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 28 21:43:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 28 21:43:52 volumio volumio[971]: info: CoreCommandRouter::volumioStop
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::stop
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::updateTrackBlock
Aug 28 21:43:52 volumio volumio[971]: info: CorePlayQueue::getTrackBlock
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::stPlaybackTimer
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::pushState
Aug 28 21:43:52 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:52 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 28 21:43:52 volumio volumio[971]: info: CoreCommandRouter::volumioPushState
Aug 28 21:43:52 volumio volumio[971]: info: CoreStateMachine::serviceStop
Aug 28 21:43:52 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:52 volumio volumio[971]: info: CoreCommandRouter::serviceStop
Aug 28 21:43:52 volumio volumio[971]: info: [1787921032666] ControllerWebradio::stop
Aug 28 21:43:52 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand stop
Aug 28 21:43:52 volumio volumio[971]: info: FusionDsp - Volumio is not playing
Aug 28 21:43:52 volumio volumio[971]: info: FusionDsp - Clipped samples monitor stopped
Aug 28 21:43:52 volumio volumio[971]: info: sendMpdCommand stop took 18 milliseconds
Aug 28 21:43:53 volumio volumio[971]: info: CoreCommandRouter::volumioPlay
Aug 28 21:43:53 volumio volumio[971]: info: CoreStateMachine::play index undefined
Aug 28 21:43:53 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 28 21:43:53 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:53 volumio volumio[971]: info: CoreStateMachine::startPlaybackTimer
Aug 28 21:43:53 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:53 volumio volumio[971]: info: [1787921033601] ControllerWebradio::clearAddPlayTrack
Aug 28 21:43:53 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand stop
Aug 28 21:43:53 volumio volumio[971]: info: sendMpdCommand stop took 1 milliseconds
Aug 28 21:43:53 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand clear
Aug 28 21:43:53 volumio volumio[971]: info:
Aug 28 21:43:53 volumio volumio[971]: ---------------------------- MPD announces system playlist update
Aug 28 21:43:53 volumio volumio[971]: info: Ignoring MPD Status Update
Aug 28 21:43:53 volumio volumio[971]: info: sendMpdCommand clear took 2 milliseconds
Aug 28 21:43:53 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm"
Aug 28 21:43:53 volumio volumio[971]: error: updateQueue error: null
Aug 28 21:43:53 volumio volumio[971]: info: ------------------------------ 4ms
Aug 28 21:43:53 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm"
Aug 28 21:43:53 volumio volumio[971]: info:
Aug 28 21:43:53 volumio volumio[971]: ---------------------------- MPD announces system playlist update
Aug 28 21:43:53 volumio volumio[971]: info: Ignoring MPD Status Update
Aug 28 21:43:53 volumio volumio[971]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 2 milliseconds
Aug 28 21:43:53 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 28 21:43:53 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand play
Aug 28 21:43:53 volumio volumio[971]: info: ------------------------------ 3ms
Aug 28 21:43:53 volumio volumio[971]: info: sendMpdCommand play took 4 milliseconds
Aug 28 21:43:54 volumio volumio[971]: info:
Aug 28 21:43:54 volumio volumio[971]: ---------------------------- MPD announces state update: player
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::getState
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status
Aug 28 21:43:54 volumio volumio[971]: info:
Aug 28 21:43:54 volumio volumio[971]: ---------------------------- MPD announces state update: player
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand status took 65 milliseconds
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::getState
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseState
Aug 28 21:43:54 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:54 volumio audio-keepalive[2400]: audio-keepalive: reopen for client rate=384000 ch=2 fmt=S24_3LE (was 48000/2/S16_LE)
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand status took 3 milliseconds
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand playlistinfo took 2 milliseconds
Aug 28 21:43:54 volumio audio-keepalive[2400]: audio-keepalive: opened keepaliveProxyOut rate=384000 ch=2 fmt=S24_3LE period=2048 buffer=16384
Aug 28 21:43:54 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE (reopened output)
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseState
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::servicePushState
Aug 28 21:43:54 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:54 volumio volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""}
Aug 28 21:43:54 volumio volumio[971]: verbose: CURRENT POSITION 0
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState stateService play
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus stop
Aug 28 21:43:54 volumio volumio[971]: info: ------------------------------ 76ms
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand playlistinfo took 3 milliseconds
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::servicePushState
Aug 28 21:43:54 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:54 volumio volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""}
Aug 28 21:43:54 volumio volumio[971]: verbose: CURRENT POSITION 0
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState stateService play
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus play
Aug 28 21:43:54 volumio volumio[971]: info: Received an update from plugin. extracting info from payload
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::volumioPushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::volumioPushState
Aug 28 21:43:54 volumio volumio[971]: info: ------------------------------ 35ms
Aug 28 21:43:54 volumio volumio[971]: info: FusionDsp - Volumio is playing
Aug 28 21:43:54 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:43:54 volumio volumio[971]: info: FusionDsp - Clipping Monitor started
Aug 28 21:43:54 volumio volumio[971]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32
Aug 28 21:43:54 volumio volumio[971]: info: FusionDsp - ---- read samplerate from file: 384000
Aug 28 21:43:54 volumio volumio[971]: info:
Aug 28 21:43:54 volumio volumio[971]: ---------------------------- MPD announces system playlist update
Aug 28 21:43:54 volumio volumio[971]: info: Ignoring MPD Status Update
Aug 28 21:43:54 volumio volumio[971]: info:
Aug 28 21:43:54 volumio volumio[971]: ---------------------------- MPD announces state update: player
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::getState
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand status
Aug 28 21:43:54 volumio volumio[971]: info: ------------------------------ 6ms
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand status took 3 milliseconds
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseState
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 28 21:43:54 volumio volumio[971]: info: sendMpdCommand playlistinfo took 1 milliseconds
Aug 28 21:43:54 volumio volumio[971]: verbose: ControllerMpd::parseTrackInfo
Aug 28 21:43:54 volumio volumio[971]: info: ControllerMpd::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::servicePushState
Aug 28 21:43:54 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:54 volumio volumio[971]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""}
Aug 28 21:43:54 volumio volumio[971]: verbose: CURRENT POSITION 0
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState stateService play
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus play
Aug 28 21:43:54 volumio volumio[971]: info: Received an update from plugin. extracting info from payload
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CorePlayQueue::getTrack 0
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::volumioPushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreStateMachine::pushState
Aug 28 21:43:54 volumio volumio[971]: info: CoreCommandRouter::volumioPushState
Aug 28 21:43:54 volumio volumio[971]: info: ------------------------------ 50ms
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:55 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Aug 28 21:43:55 volumio volumio[971]: info: camilladsp respawn in 100 ms (attempt 1/10)
Aug 28 21:43:55 volumio volumio[971]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 28 21:43:55 volumio volumio[971]: error: FusionDsp - Reload WebSocket error: [object Object]
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:55 volumio volumio[971]: info: camilladsp respawn in 200 ms (attempt 2/10)
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:55 volumio volumio[971]: info: camilladsp respawn in 400 ms (attempt 3/10)
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:55 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:43:56 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:56 volumio volumio[971]: info: camilladsp respawn in 800 ms (attempt 4/10)
Aug 28 21:43:56 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:56 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:43:56 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:56 volumio volumio[971]: info: camilladsp respawn in 1600 ms (attempt 5/10)
Aug 28 21:43:58 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:43:58 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:43:58 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:43:58 volumio volumio[971]: info: camilladsp respawn in 3200 ms (attempt 6/10)
Aug 28 21:44:01 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:44:01 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:44:01 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:44:01 volumio volumio[971]: info: camilladsp respawn in 6400 ms (attempt 7/10)
Aug 28 21:44:03 volumio ntpd[910]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Aug 28 21:44:08 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:44:08 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:44:08 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:44:08 volumio volumio[971]: info: camilladsp respawn in 10000 ms (attempt 8/10)
Aug 28 21:44:13 volumio volumio[971]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/bookworm/armhf
Aug 28 21:44:13 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:13.196+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:14 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:44:14 volumio volumio[971]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/bookworm/armhf
Aug 28 21:44:14 volumio volumio[971]: info: Folder /tmp/plugins removed
Aug 28 21:44:14 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:14.847+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:14 volumio volumio[971]: info: Check plugin dependencies
Aug 28 21:44:14 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:14.997+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 28 21:44:15 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:15.023+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio volumio[971]: info: Checking if plugin already exists
Aug 28 21:44:15 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:15.028+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio volumio[971]: info: Rename folder
Aug 28 21:44:15 volumio volumio[971]: info: Folder /tmp/downloaded_plugin.zip removed
Aug 28 21:44:15 volumio volumio[971]: info: Move to category
Aug 28 21:44:15 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:15.050+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio volumio[971]: info: Checking if install.sh is present
Aug 28 21:44:15 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:15.257+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio volumio[971]: info: Executing install.sh
Aug 28 21:44:15 volumio sudo[2766]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/Systeminfo/install.sh
Aug 28 21:44:15 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 21:44:15 volumio volumio[971]: info: Installing systeminfo
Aug 28 21:44:15 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:15.311+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:15 volumio sudo[2768]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update
Aug 28 21:44:15 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:44:16 volumio volumio[971]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease
Aug 28 21:44:16 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:16.124+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:16 volumio volumio[971]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease
Aug 28 21:44:16 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:16.847+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:18 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:44:18 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:44:18 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:44:18 volumio volumio[971]: info: camilladsp respawn in 10000 ms (attempt 9/10)
Aug 28 21:44:21 volumio volumio[971]: info: Reading package lists...
Aug 28 21:44:21 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:21.910+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:22 volumio volumio[971]: info: Building dependency tree...
Aug 28 21:44:22 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:22.491+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:22 volumio volumio[971]: info: Reading state information...
Aug 28 21:44:22 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:22.510+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:22 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root
Aug 28 21:44:22 volumio volumio[971]: info: 89 packages can be upgraded. Run 'apt list --upgradable' to see them.
Aug 28 21:44:22 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:22.570+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:22 volumio sudo[2927]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt install -y sysbench
Aug 28 21:44:22 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:44:22 volumio volumio[971]: info: Reading package lists...
Aug 28 21:44:22 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:22.694+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: Building dependency tree...
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.198+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: Reading state information...
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.206+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: You might want to run 'apt --fix-broken install' to correct these.
Aug 28 21:44:23 volumio volumio[971]: info: The following packages have unmet dependencies:
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.241+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.245+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: libpython3.11 : Depends: libpython3.11-stdlib (= 3.11.2-6+deb12u8) but 3.11.2-6+deb12u6 is to be installed
Aug 28 21:44:23 volumio volumio[971]: info: libpython3.11-stdlib : Depends: libpython3.11-minimal (= 3.11.2-6+deb12u6) but 3.11.2-6+deb12u8 is to be installed
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.801+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.807+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: python3.11 : Depends: libpython3.11-stdlib (= 3.11.2-6+deb12u8) but 3.11.2-6+deb12u6 is to be installed
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.819+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: sysbench : Depends: libaio1 (>= 0.3.93) but it is not going to be installed
Aug 28 21:44:23 volumio volumio[971]: info: Depends: libluajit2-5.1-2 (>= 2.0.4) but it is not going to be installed or
Aug 28 21:44:23 volumio volumio[971]: info: libluajit-5.1-2 (>= 2.1.0~beta3) but it is not going to be installed
Aug 28 21:44:23 volumio volumio[971]: info: Depends: libmariadb3 (>= 3.0.0) but it is not going to be installed
Aug 28 21:44:23 volumio volumio[971]: info: Depends: libpq5 but it is not going to be installed
Aug 28 21:44:23 volumio volumio[971]: info: Configuration file doesn't exist, nothing to do
Aug 28 21:44:23 volumio sudo[2950]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/user_interface/Systeminfo/c/hw_params_arm /data/plugins/user_interface/Systeminfo/hw_params
Aug 28 21:44:23 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.845+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.846+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.848+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.850+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.852+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.853+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio sudo[2952]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/Systeminfo/hw_params
Aug 28 21:44:23 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 28 21:44:23 volumio volumio[971]: info: Detected cpu architecture as armv7l
Aug 28 21:44:23 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root
Aug 28 21:44:23 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.885+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: Install script completed
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.893+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: Adding reference to registry
Aug 28 21:44:23 volumio volumio5-onboarding[1581]: time=2026-08-28T21:44:23.902+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Aug 28 21:44:23 volumio volumio[971]: info: Done installing plugin.
Aug 28 21:44:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 28 21:44:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 28 21:44:23 volumio volumio[971]: Plugin install end detected on script
Aug 28 21:44:23 volumio volumio[971]: info: Folder /tmp/plugins removed
Aug 28 21:44:23 volumio volumio[971]: info: Folder /tmp/downloaded_plugin.zip removed
Aug 28 21:44:23 volumio volumio[971]: info: Folder /data/temp removed
Aug 28 21:44:25 volumio volumio[971]: error: FusionDsp - Monitor WebSocket error: [object Object]
Aug 28 21:44:25 volumio volumio[971]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Aug 28 21:44:25 volumio volumio[971]: info: Enabling plugin Systeminfo
Aug 28 21:44:25 volumio volumio[971]: info: Loading plugin "Systeminfo"...
Aug 28 21:44:25 volumio volumio[971]: info: PLUGIN START: Systeminfo
Aug 28 21:44:25 volumio volumio[971]: info: Done.
Aug 28 21:44:26 volumio volumio[971]: info: Enabling plugin Systeminfo
Aug 28 21:44:26 volumio volumio[971]: info: Loading plugin "Systeminfo"...
Aug 28 21:44:26 volumio volumio[971]: info: PLUGIN START: Systeminfo
Aug 28 21:44:26 volumio volumio[971]: info: Done.
Aug 28 21:44:27 volumio volumio[971]: info: Enabling plugin Systeminfo
Aug 28 21:44:27 volumio volumio[971]: info: Loading plugin "Systeminfo"...
Aug 28 21:44:27 volumio volumio[971]: info: PLUGIN START: Systeminfo
Aug 28 21:44:27 volumio volumio[971]: info: Done.
Aug 28 21:44:28 volumio volumio[971]: info: Enabling plugin Systeminfo
Aug 28 21:44:28 volumio volumio[971]: info: Loading plugin "Systeminfo"...
Aug 28 21:44:28 volumio volumio[971]: info: PLUGIN START: Systeminfo
Aug 28 21:44:28 volumio volumio[971]: info: Done.
Aug 28 21:44:28 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:44:28 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:44:28 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:44:28 volumio volumio[971]: info: camilladsp respawn in 10000 ms (attempt 10/10)
Aug 28 21:44:32 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 21:44:34 volumio volumio[971]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Aug 28 21:44:38 volumio audio-keepalive[2400]: audio-keepalive: client connected
Aug 28 21:44:38 volumio audio-keepalive[2400]: audio-keepalive: client open rate=384000 ch=2 fmt=S24_3LE
Aug 28 21:44:38 volumio audio-keepalive[2400]: audio-keepalive: client disconnected
Aug 28 21:44:38 volumio volumio[971]: error: camilladsp exceeded max consecutive respawns (10); stopping respawn. Plugin restart required.
Aug 28 21:44:40 volumio volumio[971]: info: CALLMETHOD: user_interface Systeminfo runBench undefined
Aug 28 21:44:40 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , runBench
Aug 28 21:44:40 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 28 21:44:40 volumio volumio[971]: info: Systeminfo: --[object Object]
Aug 28 21:44:40 volumio volumio[971]: info: Systeminfo web UI listening on port 12334
Aug 28 21:44:41 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 28 21:44:41 volumio volumio[971]: info: Systeminfo: --[object Object]
Aug 28 21:44:41 volumio volumio[971]: warn: Systeminfo web server failed to start:
Aug 28 21:44:42 volumio volumio[971]: info: CALLMETHOD: user_interface Systeminfo runSysbench undefined
Aug 28 21:44:42 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , runSysbench
Aug 28 21:44:42 volumio volumio[971]: info: Starting full sysbench sequence...
Aug 28 21:44:42 volumio volumio[971]: error: Sysbench failed: Command failed: sysbench cpu --threads=$(nproc) --time=10 run
Aug 28 21:44:42 volumio volumio[971]: /bin/sh: 1: sysbench: not found
Aug 28 21:44:42 volumio volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 28 21:44:42 volumio volumio[971]: Error: Command failed: sysbench cpu --threads=$(nproc) --time=10 run
Aug 28 21:44:42 volumio volumio[971]: /bin/sh: 1: sysbench: not found
Aug 28 21:44:42 volumio volumio[971]: at ChildProcess.exithandler (node:child_process:421:12)
Aug 28 21:44:42 volumio volumio[971]: at ChildProcess.emit (node:events:514:28)
Aug 28 21:44:42 volumio volumio[971]: at maybeClose (node:internal/child_process:1105:16)
Aug 28 21:44:42 volumio volumio[971]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) {
Aug 28 21:44:42 volumio volumio[971]: code: 127,
Aug 28 21:44:42 volumio volumio[971]: killed: false,
Aug 28 21:44:42 volumio volumio[971]: signal: null,
Aug 28 21:44:42 volumio volumio[971]: cmd: 'sysbench cpu --threads=$(nproc) --time=10 run'
Aug 28 21:44:42 volumio volumio[971]: }
Aug 28 21:44:42 volumio volumio[971]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 28 21:44:42 volumio sudo[3041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 21:43'
Aug 28 21:44:42 volumio sudo[3041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"