-- Logs begin at Mon 2026-01-26 03:56:35 PST, end at Wed 2026-01-28 10:02:10 PST. -- Jan 28 10:01:46 volumio volumio[1498]: info: CoreCommandRouter::volumioGetState Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::volumioStop Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::stop Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::stPlaybackTimer Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::updateTrackBlock Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrackBlock Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::volumioPushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::serviceStop Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::serviceStop Jan 28 10:01:55 volumio volumio[1498]: info: [1769623315667] ControllerWebradio::stop Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 10:01:55 volumio volumio[1498]: info: Jan 28 10:01:55 volumio volumio[1498]: ---------------------------- MPD announces state update: player Jan 28 10:01:55 volumio volumio[1498]: info: sendMpdCommand stop took 4 milliseconds Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::getState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:01:55 volumio volumio[1498]: info: Jan 28 10:01:55 volumio volumio[1498]: ---------------------------- MPD announces state update: player Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::getState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:01:55 volumio volumio[1498]: info: Jan 28 10:01:55 volumio volumio[1498]: ---------------------------- MPD announces state update: player Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::getState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:01:55 volumio volumio[1498]: info: sendMpdCommand status took 1 milliseconds Jan 28 10:01:55 volumio volumio[1498]: info: sendMpdCommand status took 0 milliseconds Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::parseState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::parseState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::parseState Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:01:55 volumio volumio[1498]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:01:55 volumio volumio[1498]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::servicePushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::volumioPushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::servicePushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::volumioPushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 28 10:01:55 volumio volumio[1498]: info: ControllerMpd::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::servicePushState Jan 28 10:01:55 volumio volumio[1498]: info: CoreStateMachine::pushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: CoreCommandRouter::volumioPushState Jan 28 10:01:55 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:01:55 volumio volumio[1498]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 28 10:01:55 volumio volumio[1498]: info: ------------------------------ 9ms Jan 28 10:01:55 volumio volumio[1498]: info: ------------------------------ 8ms Jan 28 10:01:55 volumio snapserver[11364]: State changed: VolumioLoopback, state: playing => idle Jan 28 10:01:55 volumio snapserver[11364]: onStateChanged (VolumioLoopback): idle Jan 28 10:01:55 volumio snapserver[11364]: Removing 6 inactive session(s), active sessions: 2 Jan 28 10:01:56 volumio snapclient[11478]: Exception: Not enough frames available, requested frames: 959, available: 337 Jan 28 10:01:56 volumio snapclient[11478]: Failed to get chunk Jan 28 10:02:01 volumio snapclient[11478]: No chunk received for 5000ms. Closing ALSA. Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 10:02:03 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 10:02:10 volumio volumio[1498]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 10:02:10 volumio volumio[1498]: info: Preparing to save Alsa Options, stopping services first Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::volumioGetState Jan 28 10:02:10 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::volumioStop Jan 28 10:02:10 volumio volumio[1498]: info: CoreStateMachine::stop Jan 28 10:02:10 volumio volumio[1498]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 10:02:10 volumio volumio[1498]: info: Saving Audio Output to: {"output_device":{"value":"1,8","label":"HDMI 2"}} Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 10:02:10 volumio volumio[1498]: info: Found match in Cards Database: setting mixer for card HDMI 2 Jan 28 10:02:10 volumio volumio[1498]: info: Setting mixer IEC958 for card HDMI 2 Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 10:02:10 volumio volumio[1498]: info: Updating Volume Controller Parameters: Device: 1,8 Name: HDMI 2 Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 10:02:10 volumio volumio[1498]: info: Disabling external Volume Control Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 10:02:10 volumio volumio[1498]: info: Preparing to generate the ALSA configuration file Jan 28 10:02:10 volumio volumio[1498]: Unable to parse: Jan 28 10:02:10 volumio volumio[1498]: Simple mixer control 'IEC958',0 Jan 28 10:02:10 volumio volumio[1498]: Capabilities: pswitch pswitch-joined Jan 28 10:02:10 volumio volumio[1498]: Playback channels: Mono Jan 28 10:02:10 volumio volumio[1498]: Mono: Playback [on] Jan 28 10:02:10 volumio volumio[1498]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 10:02:10 volumio volumio[1498]: Unable to parse: Jan 28 10:02:10 volumio volumio[1498]: Simple mixer control 'IEC958',0 Jan 28 10:02:10 volumio volumio[1498]: Capabilities: pswitch pswitch-joined Jan 28 10:02:10 volumio volumio[1498]: Playback channels: Mono Jan 28 10:02:10 volumio volumio[1498]: Mono: Playback [on] Jan 28 10:02:10 volumio volumio[1498]: info: VolumeController:: Volume=undefined Mute =false Jan 28 10:02:10 volumio volumio[1498]: info: CoreStateMachine::pushState Jan 28 10:02:10 volumio volumio[1498]: info: CorePlayQueue::getTrack 0 Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:02:10 volumio volumio[1498]: info: CoreCommandRouter::volumioPushState Jan 28 10:02:10 volumio volumio[1498]: info: Asound.conf file written Jan 28 10:02:10 volumio sudo[20988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 10:02:10 volumio sudo[20988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 10:02:10 volumio sudo[20988]: pam_unix(sudo:session): session closed for user root Jan 28 10:02:10 volumio volumio[1498]: /bin/mv: '/home/volumio/.asoundrc' and '/etc/asound.conf' are the same file Jan 28 10:02:10 volumio volumio[1498]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:02:10 volumio volumio[1498]: Error: Command failed: /usr/bin/sudo /bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 10:02:10 volumio volumio[1498]: /bin/mv: '/home/volumio/.asoundrc' and '/etc/asound.conf' are the same file Jan 28 10:02:10 volumio volumio[1498]: at checkExecSyncError (child_process.js:790:11) Jan 28 10:02:10 volumio volumio[1498]: at execSync (child_process.js:863:15) Jan 28 10:02:10 volumio volumio[1498]: at /volumio/app/plugins/audio_interface/alsa_controller/index.js:2218:13 Jan 28 10:02:10 volumio volumio[1498]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Jan 28 10:02:10 volumio volumio[1498]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Jan 28 10:02:10 volumio volumio[1498]: at FSReqCallback.oncomplete (fs.js:180:23) { Jan 28 10:02:10 volumio volumio[1498]: status: 1, Jan 28 10:02:10 volumio volumio[1498]: signal: null, Jan 28 10:02:10 volumio volumio[1498]: output: [ Jan 28 10:02:10 volumio volumio[1498]: null, Jan 28 10:02:10 volumio volumio[1498]: '', Jan 28 10:02:10 volumio volumio[1498]: "/bin/mv: '/home/volumio/.asoundrc' and '/etc/asound.conf' are the same file\n" Jan 28 10:02:10 volumio volumio[1498]: ], Jan 28 10:02:10 volumio volumio[1498]: pid: 20987, Jan 28 10:02:10 volumio volumio[1498]: stdout: '', Jan 28 10:02:10 volumio volumio[1498]: stderr: "/bin/mv: '/home/volumio/.asoundrc' and '/etc/asound.conf' are the same file\n" Jan 28 10:02:10 volumio volumio[1498]: } Jan 28 10:02:10 volumio volumio[1498]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:02:10 volumio sudo[20999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 10:01 Jan 28 10:02:10 volumio sudo[20999]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"