Apr 12 19:48:02 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Apr 12 19:48:03 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Apr 12 19:48:03 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Apr 12 19:48:04 volumio volumio[1363]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.106 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_2_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 12 19:48:04 volumio volumio[1363]: info: CoreCommandRouter::volumioGetState Apr 12 19:48:04 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:04 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 19:48:04 volumio volumio[1363]: info: Listing playlists Apr 12 19:48:04 volumio volumio[1363]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 19:48:04 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:48:04 volumio volumio[1363]: info: CoreCommandRouter::volumioGetQueue Apr 12 19:48:04 volumio volumio[1363]: info: CoreStateMachine::getQueue Apr 12 19:48:04 volumio volumio[1363]: info: CorePlayQueue::getQueue Apr 12 19:48:05 volumio volumio[1363]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.106 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_2_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Apr 12 19:48:05 volumio volumio[1363]: info: CoreCommandRouter::volumioGetState Apr 12 19:48:05 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:05 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 19:48:05 volumio volumio[1363]: info: Listing playlists Apr 12 19:48:05 volumio volumio[1363]: info: CoreCommandRouter::volumioGetQueue Apr 12 19:48:05 volumio volumio[1363]: info: CoreStateMachine::getQueue Apr 12 19:48:05 volumio volumio[1363]: info: CorePlayQueue::getQueue Apr 12 19:48:05 volumio volumio[1363]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 19:48:05 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:48:40 volumio volumio[1363]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::volumioGetState Apr 12 19:48:40 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:40 volumio volumio[1363]: info: Restoring Previous Volume level: 100 false true Apr 12 19:48:40 volumio volumio[1363]: info: VolumeController::SetAlsaVolume100 Apr 12 19:48:40 volumio volumio[1363]: info: Enable softmixer device for audio device number 0,0 Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::volumioStop Apr 12 19:48:40 volumio volumio[1363]: info: CoreStateMachine::stop Apr 12 19:48:40 volumio volumio[1363]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 19:48:40 volumio volumio[1363]: info: Enable softmixer device for audio device undefined Apr 12 19:48:40 volumio volumio[1363]: info: Output device has changed, restarting MPD Apr 12 19:48:40 volumio volumio[1363]: info: Output device has changed, restarting Shairport Sync Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:40 volumio sudo[7501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:48:40 volumio sudo[7501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:40 volumio sudo[7501]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:40 volumio sudo[7503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:48:40 volumio sudo[7503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:40 volumio volumio[1363]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:48:40 volumio volumio[1363]: info: Volume configurations have been set Apr 12 19:48:40 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 19:48:40 volumio volumio[1363]: info: Updating Volume Controller Parameters: Device: 0,0 Name: HDMI 0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 19:48:40 volumio volumio[1363]: info: Disabling external Volume Control Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:48:40 volumio volumio[1363]: info: CoreStateMachine::pushState Apr 12 19:48:40 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 19:48:40 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 19:48:40 volumio systemd[1]: mpd.service: Consumed 1.494s CPU time. Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 19:48:40 volumio volumio[1363]: info: CoreCommandRouter::volumioPushState Apr 12 19:48:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 19:48:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 19:48:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 19:48:40 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:40.990Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" state=STATUS_STOPPED positionMs=170598 volume=100 Apr 12 19:48:40 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:40.990Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" id="mnt/USB/8E13-5931/vk музыка/12 PM (Original Mix).mp3" title="12 PM (Original Mix)" Apr 12 19:48:41 volumio volumio[1363]: info: CoreStateMachine::pushState Apr 12 19:48:41 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::volumioPushState Apr 12 19:48:41 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:41.013Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" state=STATUS_STOPPED positionMs=170598 volume=100 Apr 12 19:48:41 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:41.014Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" id="mnt/USB/8E13-5931/vk музыка/12 PM (Original Mix).mp3" title="12 PM (Original Mix)" Apr 12 19:48:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 19:48:41 volumio volumio[1363]: info: MPD Permissions set Apr 12 19:48:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 19:48:41 volumio volumio[1363]: info: Software Volume ALSA configuration written Apr 12 19:48:41 volumio volumio[1363]: info: Preparing to generate the ALSA configuration file Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio sudo[7522]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 19:48:41 volumio sudo[7522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 19:48:41 volumio volumio[1363]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 12 19:48:41 volumio volumio[1363]: info: Reading ALSA contributions from plugins. Apr 12 19:48:41 volumio sudo[7522]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:41 volumio volumio[1363]: info: Starting Shairport Sync Apr 12 19:48:41 volumio volumio[1363]: info: Asound.conf file written Apr 12 19:48:41 volumio sudo[7544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:48:41 volumio sudo[7544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:41 volumio sudo[7549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 19:48:41 volumio sudo[7549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 19:48:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 19:48:41 volumio sudo[7549]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 19:48:41 volumio systemd[1]: shairport-sync.service: Consumed 6.694s CPU time. Apr 12 19:48:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 19:48:41 volumio sudo[7544]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:41 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 19:48:41 volumio volumio[1363]: No state is present for card Audio Apr 12 19:48:41 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 19:48:41 volumio volumio[1363]: Found hardware: "HdmiLpeAudio" "" "" "" "" Apr 12 19:48:41 volumio volumio[1363]: Hardware is initialized using a generic method Apr 12 19:48:41 volumio volumio[1363]: No state is present for card Audio Apr 12 19:48:41 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 19:48:41 volumio volumio[1363]: No state is present for card bytchtnocodec Apr 12 19:48:41 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 19:48:41 volumio volumio[1363]: Found hardware: "bytcht-nocodec" "" "" "" "" Apr 12 19:48:41 volumio volumio[1363]: Hardware is initialized using a generic method Apr 12 19:48:41 volumio volumio[1363]: No state is present for card bytchtnocodec Apr 12 19:48:41 volumio volumio[1363]: info: Output device has changed, restarting MPD Apr 12 19:48:41 volumio volumio[1363]: info: Output device has changed, restarting Shairport Sync Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:41 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:41 volumio sudo[7569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:48:41 volumio sudo[7569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:41 volumio volumio[1363]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:48:41 volumio sudo[7558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:48:41 volumio sudo[7558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:41 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 19:48:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 19:48:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 19:48:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 19:48:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 19:48:41 volumio sudo[7558]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 19:48:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 19:48:41 volumio sudo[7599]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 19:48:41 volumio sudo[7599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 19:48:41 volumio sudo[7599]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:42 volumio volumio[1363]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 12 19:48:42 volumio volumio[1363]: underrun!!! (at least 0.016 ms long) Apr 12 19:48:42 volumio volumio[1363]: underrun!!! (at least 0.014 ms long) Apr 12 19:48:42 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 19:48:42 volumio volumio[1363]: No state is present for card Audio Apr 12 19:48:42 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 19:48:42 volumio volumio[1363]: Found hardware: "HdmiLpeAudio" "" "" "" "" Apr 12 19:48:42 volumio volumio[1363]: Hardware is initialized using a generic method Apr 12 19:48:42 volumio volumio[1363]: No state is present for card Audio Apr 12 19:48:42 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 19:48:42 volumio volumio[1363]: No state is present for card bytchtnocodec Apr 12 19:48:42 volumio volumio[1363]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 19:48:42 volumio volumio[1363]: Found hardware: "bytcht-nocodec" "" "" "" "" Apr 12 19:48:42 volumio volumio[1363]: Hardware is initialized using a generic method Apr 12 19:48:42 volumio volumio[1363]: No state is present for card bytchtnocodec Apr 12 19:48:42 volumio volumio[1363]: info: Output device has changed, restarting MPD Apr 12 19:48:42 volumio volumio[1363]: info: Output device has changed, restarting Shairport Sync Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:42 volumio sudo[7607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:48:42 volumio sudo[7607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:42 volumio volumio[1363]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:48:42 volumio sudo[7607]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:42 volumio sudo[7609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:48:42 volumio sudo[7609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 19:48:42 volumio volumio[1363]: info: Updating Volume Controller Parameters: Device: 0,0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 19:48:42 volumio volumio[1363]: info: Disabling external Volume Control Apr 12 19:48:42 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 19:48:42 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 19:48:42 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 19:48:42 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 19:48:42 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 19:48:42 volumio volumio[1363]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 12 19:48:42 volumio volumio[1363]: info: MPD Permissions set Apr 12 19:48:42 volumio volumio[1363]: info: MPD Permissions set Apr 12 19:48:42 volumio volumio[1363]: info: Shairport-Sync Started Apr 12 19:48:42 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 19:48:42 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 19:48:42 volumio volumio[1363]: (node:1363) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 12 19:48:42 volumio volumio[1363]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 12 19:48:42 volumio volumio[1363]: (node:1363) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 12 19:48:42 volumio volumio[1363]: (node:1363) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 12 19:48:42 volumio volumio[1363]: (node:1363) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 12 19:48:42 volumio volumio[1363]: (node:1363) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 12 19:48:42 volumio volumio[1363]: info: CoreStateMachine::pushState Apr 12 19:48:42 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::volumioPushState Apr 12 19:48:42 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:42.278Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" state=STATUS_STOPPED positionMs=171996 volume=100 Apr 12 19:48:42 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:42.278Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" id="mnt/USB/8E13-5931/vk музыка/12 PM (Original Mix).mp3" title="12 PM (Original Mix)" Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 19:48:42 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:48:42 volumio sudo[7628]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 19:48:42 volumio sudo[7628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 19:48:42 volumio volumio[1363]: info: Starting Shairport Sync Apr 12 19:48:42 volumio sudo[7628]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:42 volumio volumio[1363]: info: Starting Shairport Sync Apr 12 19:48:42 volumio sudo[7647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:48:42 volumio sudo[7647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:42 volumio sudo[7651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:48:42 volumio sudo[7651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:42 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 19:48:42 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 19:48:42 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 19:48:42 volumio systemd[1]: shairport-sync.service: Consumed 1.126s CPU time. Apr 12 19:48:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 19:48:42 volumio sudo[7647]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:42 volumio sudo[7651]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:42 volumio volumio[1363]: info: Shairport-Sync Started Apr 12 19:48:42 volumio volumio[1363]: info: Shairport-Sync Started Apr 12 19:48:43 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 12 19:48:43 volumio sudo[7672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:48:43 volumio sudo[7672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:48:43 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 12 19:48:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 19:48:43 volumio systemd[1]: mpd.service: Consumed 1.450s CPU time. Apr 12 19:48:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 12 19:48:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 19:48:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 19:48:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 19:48:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 19:48:43 volumio sudo[7676]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 19:48:43 volumio sudo[7676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 19:48:43 volumio sudo[7676]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:45 volumio mpd[7678]: 2026-04-12T19:48:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 19:48:45 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 12 19:48:45 volumio sudo[7672]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:45 volumio sudo[7609]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:45 volumio sudo[7569]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:45 volumio sudo[7503]: pam_unix(sudo:session): session closed for user root Apr 12 19:48:45 volumio volumio[1363]: error: MPD error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: MPD error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: MPD error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: The expression evaluated to a falsy value: Apr 12 19:48:45 volumio volumio[1363]: assert.ok(self.idling) Apr 12 19:48:45 volumio volumio[1363]: error: updateQueue error: null Apr 12 19:48:45 volumio volumio[1363]: info: VolumeController::SetAlsaVolume100 Apr 12 19:48:45 volumio volumio[1363]: info: CoreStateMachine::pushState Apr 12 19:48:45 volumio volumio[1363]: info: CorePlayQueue::getTrack 13 Apr 12 19:48:45 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 19:48:45 volumio volumio[1363]: info: CoreCommandRouter::volumioPushState Apr 12 19:48:45 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:45.390Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" state=STATUS_STOPPED positionMs=175010 volume=100 Apr 12 19:48:45 volumio volumio5-onboarding[2026]: time=2026-04-12T19:48:45.391Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.106:65328 @ 0xc00025c2d0" id="mnt/USB/8E13-5931/vk музыка/12 PM (Original Mix).mp3" title="12 PM (Original Mix)" Apr 12 19:48:45 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 19:49:08 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 12 19:49:14 volumio volumio[1363]: info: Received Get System Version Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 19:49:14 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: info: Retrieving Cloud Streaming UI Apr 12 19:49:30 volumio volumio[1363]: info: Getting Tidal Cloud Configuration Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: info: Getting Qobuz Cloud Configuration Apr 12 19:49:30 volumio volumio[1363]: info: Asking plugin for UI Config Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: info: Getting Spotify Cloud Configuration Apr 12 19:49:30 volumio volumio[1363]: info: Asking plugin for UI Config Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: info: Saving Spotify Acccount Apr 12 19:49:30 volumio volumio[1363]: info: Got Tidal Cloud Configuration Apr 12 19:49:30 volumio volumio[1363]: info: Got it Apr 12 19:49:30 volumio volumio[1363]: info: Got it Apr 12 19:49:30 volumio volumio[1363]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 19:49:30 volumio volumio[1363]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 19:49:30 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 19:49:34 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 19:49:34 volumio volumio[1363]: info: Disabling MyMusic plugin upnp Apr 12 19:49:34 volumio sudo[7840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 12 19:49:34 volumio sudo[7840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 19:49:35 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Apr 12 19:49:38 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 19:49:40 volumio volumio[1363]: info: Enabling MyMusic plugin upnp Apr 12 19:49:40 volumio volumio[1363]: info: Enabling plugin upnp Apr 12 19:49:40 volumio volumio[1363]: info: Loading plugin "upnp"... Apr 12 19:49:40 volumio volumio[1363]: info: [1776023380962] Starting Upmpd Daemon Apr 12 19:49:40 volumio volumio[1363]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 19:49:40 volumio volumio[1363]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 19:49:40 volumio volumio[1363]: Error: listen EADDRINUSE: address already in use :::6599 Apr 12 19:49:40 volumio volumio[1363]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Apr 12 19:49:40 volumio volumio[1363]: at listenInCluster (node:net:1920:12) Apr 12 19:49:40 volumio volumio[1363]: at Server.listen (node:net:2008:7) Apr 12 19:49:40 volumio volumio[1363]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 12 19:49:40 volumio volumio[1363]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 12 19:49:40 volumio volumio[1363]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 12 19:49:40 volumio volumio[1363]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 12 19:49:40 volumio volumio[1363]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Apr 12 19:49:40 volumio volumio[1363]: code: 'EADDRINUSE', Apr 12 19:49:40 volumio volumio[1363]: errno: -98, Apr 12 19:49:40 volumio volumio[1363]: syscall: 'listen', Apr 12 19:49:40 volumio volumio[1363]: address: '::', Apr 12 19:49:40 volumio volumio[1363]: port: 6599 Apr 12 19:49:40 volumio volumio[1363]: } Apr 12 19:49:40 volumio volumio[1363]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 19:49:41 volumio sudo[7863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 19:48' Apr 12 19:49:41 volumio sudo[7863]: 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"