Apr 12 23:35:02 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:02 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:03 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:03 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:05 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:05 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:08 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:08 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:11 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:11 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:14 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:14 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:17 music kernel: usb 1-2.1: USB disconnect, device number 10 Apr 12 23:35:17 music kernel: usb 1-2.1: new high-speed USB device number 11 using xhci_hcd Apr 12 23:35:17 music kernel: usb 1-2.1: New USB device found, idVendor=20b1, idProduct=301f, bcdDevice= 3.18 Apr 12 23:35:17 music kernel: usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 12 23:35:17 music kernel: usb 1-2.1: Product: Android Apr 12 23:35:17 music kernel: usb 1-2.1: Manufacturer: Android Apr 12 23:35:17 music kernel: usb 1-2.1: SerialNumber: ea6ca8ef Apr 12 23:35:17 music kernel: usb 1-2.1: 0:2 : does not exist Apr 12 23:35:17 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:17 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:17 music (udev-worker)[23036]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Apr 12 23:35:17 music volumio[16993]: info: Apr 12 23:35:17 music volumio[16993]: ---------------------------- USB Audio Device Attached Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::Close All Modals sent Apr 12 23:35:17 music volumio[16993]: info: Preparing to save Alsa Options, stopping services first Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:17 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::volumioPause Apr 12 23:35:17 music volumio[16993]: info: CoreStateMachine::pause Apr 12 23:35:17 music volumio[16993]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:35:17 music volumio[16993]: info: CoreStateMachine::servicePause Apr 12 23:35:17 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::servicePause Apr 12 23:35:17 music volumio[16993]: info: ControllerMpd::pause Apr 12 23:35:17 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand pause Apr 12 23:35:17 music volumio[16993]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Android","alsacard":"Android"},"i2s":false} Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:17 music volumio[16993]: info: Device 5 does not have any Mixer Control Available Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 23:35:17 music volumio[16993]: info: Updating Volume Controller Parameters: Device: 5 Name: Android Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 23:35:17 music volumio[16993]: info: Disabling external Volume Control Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:35:17 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:17 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:35:17 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:17 music volumio[16993]: info: Preparing to generate the ALSA configuration file Apr 12 23:35:18 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:18 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:18 music volumio[16993]: info: Apr 12 23:35:18 music volumio[16993]: ---------------------------- MPD announces state update: player Apr 12 23:35:18 music volumio[16993]: info: sendMpdCommand pause took 260 milliseconds Apr 12 23:35:18 music volumio[16993]: info: ControllerMpd::getState Apr 12 23:35:18 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:35:18 music volumio[16993]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Apr 12 23:35:18 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 23:35:18 music volumio[16993]: info: sendMpdCommand status took 25 milliseconds Apr 12 23:35:18 music volumio[16993]: verbose: ControllerMpd::parseState Apr 12 23:35:18 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:35:18 music volumio[16993]: info: sendMpdCommand clearerror took 5 milliseconds Apr 12 23:35:18 music volumio[16993]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 23:35:18 music volumio[16993]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:35:18 music volumio[16993]: info: ControllerMpd::pushState Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::servicePushState Apr 12 23:35:18 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:18 music volumio[16993]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"803 Kbps","isStreaming":false,"title":"Tiny Lolf's Journey","artist":"Trace Bundy","album":"Missile Bell","uri":"USB/MUSIK-750G/Nhạc Quốc Tế/FLAC/Tiny Lolf's Journey.flac","trackType":"flac"} Apr 12 23:35:18 music volumio[16993]: verbose: CURRENT POSITION 5 Apr 12 23:35:18 music volumio[16993]: info: CoreStateMachine::syncState stateService pause Apr 12 23:35:18 music volumio[16993]: info: CoreStateMachine::syncState currentStatus pause Apr 12 23:35:18 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:18 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:18 music volumio[16993]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:35:18 music volumio[16993]: info: ------------------------------ 56ms Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 23:35:18 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:18 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:18 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:18 music volumio[16993]: info: Asound.conf file written Apr 12 23:35:18 music sudo[23075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 23:35:18 music sudo[23075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:18 music sudo[23075]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:18 music volumio[16993]: No state is present for card Android Apr 12 23:35:18 music volumio[16993]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:301f" "" "" Apr 12 23:35:18 music volumio[16993]: Hardware is initialized using a generic method Apr 12 23:35:18 music volumio[16993]: No state is present for card Android Apr 12 23:35:18 music volumio[16993]: info: Output device has changed, restarting MPD Apr 12 23:35:18 music volumio[16993]: info: Output device has changed, restarting MPD Apr 12 23:35:18 music sudo[23082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 23:35:18 music sudo[23083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 23:35:18 music sudo[23082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 12 23:35:18 music sudo[23083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:18 music volumio[16993]: warn: Display-configuration --- sleepScreen: xscreensaver not running or failed → Command failed: DISPLAY=:0 xscreensaver-command -activate Apr 12 23:35:18 music volumio[16993]: xscreensaver-command: already active Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music sudo[23082]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music sudo[23086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 23:35:18 music sudo[23086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:18 music sudo[23086]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:18 music systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 23:35:18 music sudo[23087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 23:35:18 music sudo[23087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music systemd[1]: mpd.service: Deactivated successfully. Apr 12 23:35:18 music systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 23:35:18 music systemd[1]: mpd.service: Consumed 2.463s CPU time. Apr 12 23:35:18 music systemd[1]: mpd.socket: Deactivated successfully. Apr 12 23:35:18 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 23:35:18 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 23:35:18 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 23:35:18 music systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 23:35:18 music systemd[1]: mpd.service: Deactivated successfully. Apr 12 23:35:18 music systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 23:35:18 music systemd[1]: mpd.socket: Deactivated successfully. Apr 12 23:35:18 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 23:35:18 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 23:35:18 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:18 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 23:35:18 music volumio[16993]: info: MPD Permissions set Apr 12 23:35:18 music volumio[16993]: info: MPD Permissions set Apr 12 23:35:18 music systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 23:35:18 music sudo[23113]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 23:35:18 music sudo[23113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 23:35:18 music sudo[23113]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:19 music kernel: usb 1-2.1: USB disconnect, device number 11 Apr 12 23:35:19 music volumio[16993]: info: Apr 12 23:35:19 music volumio[16993]: ---------------------------- USB Audio Device Detached Apr 12 23:35:19 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 12 23:35:19 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:19 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 12 23:35:19 music kernel: usb 1-2.1: new high-speed USB device number 12 using xhci_hcd Apr 12 23:35:19 music kernel: usb 1-2.1: New USB device found, idVendor=20b1, idProduct=301f, bcdDevice= 3.18 Apr 12 23:35:19 music kernel: usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 12 23:35:19 music kernel: usb 1-2.1: Product: Android Apr 12 23:35:19 music kernel: usb 1-2.1: Manufacturer: Android Apr 12 23:35:19 music kernel: usb 1-2.1: SerialNumber: ea6ca8ef Apr 12 23:35:19 music kernel: usb 1-2.1: 0:2 : does not exist Apr 12 23:35:20 music (udev-worker)[23035]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Apr 12 23:35:20 music volumio[16993]: info: Apr 12 23:35:20 music volumio[16993]: ---------------------------- USB Audio Device Attached Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 12 23:35:20 music mpd[23116]: 2026-04-12T23:35:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::Close All Modals sent Apr 12 23:35:20 music volumio[16993]: info: Preparing to save Alsa Options, stopping services first Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:20 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::volumioPause Apr 12 23:35:20 music volumio[16993]: info: CoreStateMachine::pause Apr 12 23:35:20 music volumio[16993]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Android","alsacard":"Android"},"i2s":false} Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:20 music volumio[16993]: info: Device 5 does not have any Mixer Control Available Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 23:35:20 music volumio[16993]: info: Updating Volume Controller Parameters: Device: 5 Name: Android Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 23:35:20 music volumio[16993]: info: Disabling external Volume Control Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:35:20 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:20 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:20 music volumio[16993]: info: Preparing to generate the ALSA configuration file Apr 12 23:35:20 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:20 music systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 23:35:20 music sudo[23083]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:20 music sudo[23087]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:20 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 23:35:20 music volumio[16993]: info: Asound.conf file unchanged, so no further update is needed Apr 12 23:35:20 music volumio[16993]: info: Output device has changed, restarting MPD Apr 12 23:35:20 music volumio[16993]: error: MPD error: The expression evaluated to a falsy value: Apr 12 23:35:20 music volumio[16993]: assert.ok(self.idling) Apr 12 23:35:20 music volumio[16993]: error: The expression evaluated to a falsy value: Apr 12 23:35:20 music volumio[16993]: assert.ok(self.idling) Apr 12 23:35:20 music sudo[23170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 23:35:20 music sudo[23170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:20 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:20 music volumio[16993]: error: updateQueue error: null Apr 12 23:35:20 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:20 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:20 music sudo[23170]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:20 music volumio[16993]: info: MPD Permissions set Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music sudo[23171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 23:35:20 music sudo[23171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 23:35:20 music systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:20 music systemd[1]: mpd.service: Deactivated successfully. Apr 12 23:35:20 music systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 23:35:20 music systemd[1]: mpd.service: Consumed 1.494s CPU time. Apr 12 23:35:20 music systemd[1]: mpd.socket: Deactivated successfully. Apr 12 23:35:20 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 23:35:20 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 23:35:20 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 23:35:20 music systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 23:35:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 12 23:35:21 music sudo[23183]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 23:35:21 music sudo[23183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 23:35:21 music sudo[23183]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:21 music volumio[16993]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 12 23:35:21 music volumio[16993]: info: CoreStateMachine::getcurrentVolume Apr 12 23:35:21 music volumio[16993]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 23:35:21 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:21 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:21 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:21 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:22 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:22 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:22 music mpd[23186]: 2026-04-12T23:35:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 23:35:22 music systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 23:35:22 music sudo[23171]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:22 music volumio[16993]: error: updateQueue error: null Apr 12 23:35:23 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:23 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:24 music volumio[16993]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 12 23:35:24 music volumio[16993]: info: CoreStateMachine::getcurrentVolume Apr 12 23:35:24 music volumio[16993]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 23:35:24 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:24 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:24 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:24 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:24 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:24 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:26 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:26 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:29 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:29 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:31 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:35:31 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 23:35:31 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 23:35:31 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 23:35:31 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 12 23:35:32 music volumio[16993]: info: Received Get System Version Apr 12 23:35:32 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 23:35:32 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:32 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:35 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:35 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:35 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:35 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:38 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:38 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 23:35:39 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 23:35:41 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:41 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:43 music volumio[16993]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 12 23:35:43 music volumio[16993]: info: Preparing to save Alsa Options, stopping services first Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:43 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::volumioPause Apr 12 23:35:43 music volumio[16993]: info: CoreStateMachine::pause Apr 12 23:35:43 music volumio[16993]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Android"}} Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:43 music volumio[16993]: info: Device 5 does not have any Mixer Control Available Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 23:35:43 music volumio[16993]: info: Updating Volume Controller Parameters: Device: 5 Name: Android Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 23:35:43 music volumio[16993]: info: Disabling external Volume Control Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:35:43 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:43 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:43 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:43 music volumio[16993]: info: Preparing to generate the ALSA configuration file Apr 12 23:35:43 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:43 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:43 music volumio[16993]: info: Asound.conf file unchanged, so no further update is needed Apr 12 23:35:43 music volumio[16993]: info: Output device has changed, restarting MPD Apr 12 23:35:44 music sudo[23347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 23:35:44 music sudo[23347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:44 music sudo[23349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 23:35:44 music sudo[23349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 23:35:44 music sudo[23347]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 23:35:44 music systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 23:35:44 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:44 music volumio[16993]: info: MPD Permissions set Apr 12 23:35:44 music systemd[1]: mpd.service: Deactivated successfully. Apr 12 23:35:44 music systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 23:35:44 music systemd[1]: mpd.service: Consumed 1.491s CPU time. Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music systemd[1]: mpd.socket: Deactivated successfully. Apr 12 23:35:44 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 23:35:44 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 23:35:44 music systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 23:35:44 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 23:35:44 music sudo[23364]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 23:35:44 music sudo[23364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 23:35:44 music sudo[23364]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:44 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:44 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:45 music mpd[23370]: 2026-04-12T23:35:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 23:35:45 music systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 23:35:45 music sudo[23349]: pam_unix(sudo:session): session closed for user root Apr 12 23:35:45 music volumio[16993]: error: updateQueue error: null Apr 12 23:35:47 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:47 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:47 music volumio[16993]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 12 23:35:47 music volumio[16993]: info: CoreStateMachine::getcurrentVolume Apr 12 23:35:47 music volumio[16993]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 23:35:47 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:35:47 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:47 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:35:47 music volumio[16993]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=xscreensaver Apr 12 23:35:47 music volumio[16993]: info: Display-configuration --- → Sleep (xscreensaver) triggered Apr 12 23:35:48 music volumio[16993]: info: Display-configuration --- sleepScreen: xscreensaver activated (screen blanked) Apr 12 23:35:50 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:50 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:53 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:35:53 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:53 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:53 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:56 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:56 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:35:57 music volumio[16993]: info: CoreCommandRouter::volumioPlay Apr 12 23:35:57 music volumio[16993]: info: CoreStateMachine::play index undefined Apr 12 23:35:57 music volumio[16993]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:35:57 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:57 music volumio[16993]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:35:57 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:35:57 music volumio[16993]: info: ControllerMpd::resume Apr 12 23:35:57 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:35:57 music volumio[16993]: info: sendMpdCommand play took 1 milliseconds Apr 12 23:35:59 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:35:59 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:02 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 23:36:02 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 23:36:02 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:02 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:05 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:05 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:06 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:36:07 music volumio[16993]: info: Display-configuration --- Touch-related devices detected: [{"id":"10","name":"⎜ ↳ Elan Touchpad"}] Apr 12 23:36:08 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:08 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:11 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:11 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:14 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:14 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:17 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:17 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:20 music volumio[16993]: info: CALLMETHOD: user_interface display_configuration saveScreensaver [object Object] Apr 12 23:36:20 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: display_configuration , saveScreensaver Apr 12 23:36:20 music kernel: perf: interrupt took too long (4924 > 4918), lowering kernel.perf_event_max_sample_rate to 40500 Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- TTY: display=normal(0) + offset=same(0) = 0 deg (fbconv=0) Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- Plymouth: display=normal(0) + offset=same(0) = 0 deg Apr 12 23:36:20 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:36:20 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- Connected screens: eDP-1 Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- xscreensaver cleaned up before starting Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- DPMS disabled before playback state check Apr 12 23:36:20 music volumio[16993]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 23:36:20 music volumio[16993]: info: CoreCommandRouter::Close All Modals sent Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- sleepScreen: DPMS - screen off in 120s Apr 12 23:36:20 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- Rotation config saved for Grub: screen=eDP-1, plymouth=0, fbcon=0 Apr 12 23:36:20 music sudo[23566]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/graphics/fbcon/rotate_all Apr 12 23:36:20 music sudo[23566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- Runtime rotation applied: normal | Boot config (plymouth=0, fbconv=0) Apr 12 23:36:20 music sudo[23566]: pam_unix(sudo:session): session closed for user root Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- Touch-related devices detected: [{"id":"10","name":"⎜ ↳ Elan Touchpad"}] Apr 12 23:36:20 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:20 music volumio[16993]: info: Display-configuration --- fbcon console rotation set to 0 Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Connected screens: eDP-1 Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Touch-related devices detected: [{"id":"10","name":"⎜ ↳ Elan Touchpad"}] Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Touch matrix: display=normal, offset=0, matrix=1 0 0 0 1 0 0 0 1 Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Touch correction applied to ⎜ ↳ Elan Touchpad (id=10) Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) Apr 12 23:36:21 music sudo[23580]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -9 -f unclutter Apr 12 23:36:21 music sudo[23580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:36:21 music sudo[23585]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/backlight/intel_backlight/brightness Apr 12 23:36:21 music sudo[23585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 23:36:21 music sudo[23585]: pam_unix(sudo:session): session closed for user root Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- No unclutter process to stop Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- unclutter stopped Apr 12 23:36:21 music volumio[16993]: info: Display-configuration --- Brightness set to 100% (7812/7812) on intel_backlight Apr 12 23:36:23 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:23 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:26 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:26 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:28 music volumio[16993]: info: CoreCommandRouter::volumioGetState Apr 12 23:36:28 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:36:29 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:29 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:32 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:36:32 music volumio[16993]: info: CURURI: music-library Apr 12 23:36:32 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:32 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:32 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:34 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:36:34 music volumio[16993]: info: CURURI: music-library/USB Apr 12 23:36:34 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:34 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:36:34 music volumio[16993]: info: CURURI: music-library/USB/MUSIK-750G Apr 12 23:36:34 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:35 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:36 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:38 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:36:38 music volumio[16993]: info: CURURI: music-library/USB/MUSIK-750G/Yao Si Ting - The Collection Apr 12 23:36:38 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:39 music volumio[16993]: info: Initializing connection to go-librespot Websocket Apr 12 23:36:39 music volumio[16993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 12 23:36:41 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:41 music volumio[16993]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::ClearQueue Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::stop Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::updateTrackBlock Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::getTrackBlock Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::pushState Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:36:41 music volumio[16993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:36:41 music volumio[16993]: info: CoreCommandRouter::volumioPushState Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::serviceStop Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::getTrack 5 Apr 12 23:36:41 music volumio[16993]: info: CoreCommandRouter::serviceStop Apr 12 23:36:41 music volumio[16993]: info: ControllerMpd::stop Apr 12 23:36:41 music volumio[16993]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::clearPlayQueue Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::saveQueue Apr 12 23:36:41 music volumio[16993]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:36:41 music volumio[16993]: info: CoreStateMachine::addQueueItems Apr 12 23:36:41 music volumio[16993]: info: CorePlayQueue::addQueueItems Apr 12 23:36:41 music volumio[16993]: info: Preload queue cleared Apr 12 23:36:41 music volumio[16993]: info: Adding Item to queue: music-library/USB/MUSIK-750G/Yao Si Ting - The Collection/Yao Si Ting - Eternal Singing - Endless Love II (2006) [WAV] Apr 12 23:36:41 music volumio[16993]: info: Exploding uri music-library/USB/MUSIK-750G/Yao Si Ting - The Collection/Yao Si Ting - Eternal Singing - Endless Love II (2006) [WAV] in service mpd Apr 12 23:36:41 music volumio[16993]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Apr 12 23:36:41 music volumio[16993]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Apr 12 23:36:41 music volumio[16993]: info: ALBUMART /albumart?cacheid=753&web=/Eternal%20Singing%20Endless%20Love%20II/extralarge&path=%2Fmnt%2FUSB%2FMUSIK-750G%2FYao%20Si%20Ting%20-%20The%20Collection%2FYao%20Si%20Ting%20-%20Eternal%20Singing%20-%20Endless%20Love%20II%20(2006)%20%5BWAV%5D%2FYao%20Si%20Ting%20-%20Eternal%20Singing%20-%20Endless%20Love%20II.cue&metadata=false Apr 12 23:36:41 music volumio[16993]: info: URI /mnt/USB/MUSIK-750G/Yao Si Ting - The Collection/Yao Si Ting - Eternal Singing - Endless Love II (2006) [WAV]/Yao Si Ting - Eternal Singing - Endless Love II.cue Apr 12 23:36:41 music volumio[16993]: info: ALBUMART /albumart?cacheid=753&web=/Eternal%20Singing%20Endless%20Love%20II/extralarge&path=%2Fmnt%2FUSB%2FMUSIK-750G%2FYao%20Si%20Ting%20-%20The%20Collection%2FYao%20Si%20Ting%20-%20Eternal%20Singing%20-%20Endless%20Love%20II%20(2006)%20%5BWAV%5D%2FYao%20Si%20Ting%20-%20Eternal%20Singing%20-%20Endless%20Love%20II.cue&metadata=false Apr 12 23:36:41 music volumio[16993]: info: URI /mnt/USB/MUSIK-750G/Yao Si Ting - The Collection/Yao Si Ting - Eternal Singing - Endless Love II (2006) [WAV]/Yao Si Ting - Eternal Singing - Endless Love II.cue Apr 12 23:36:41 music volumio[16993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 23:36:41 music volumio[16993]: Error: Unable to resolve or reject the same promise twice Apr 12 23:36:41 music volumio[16993]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 12 23:36:41 music volumio[16993]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Apr 12 23:36:41 music volumio[16993]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 12 23:36:41 music volumio[16993]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 12 23:36:41 music volumio[16993]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 12 23:36:41 music volumio[16993]: at Socket.emit (node:events:514:28) Apr 12 23:36:41 music volumio[16993]: at addChunk (node:internal/streams/readable:343:12) Apr 12 23:36:41 music volumio[16993]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 12 23:36:41 music volumio[16993]: at Readable.push (node:internal/streams/readable:253:10) Apr 12 23:36:41 music volumio[16993]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 12 23:36:41 music volumio[16993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 23:36:42 music sudo[23701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 23:35' Apr 12 23:36:42 music sudo[23701]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"