Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 31 03:09:00 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 31 03:09:07 volumio volumio[1347]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio sudo[21694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 31 03:09:07 volumio sudo[21694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 31 03:09:07 volumio sudo[21694]: pam_unix(sudo:session): session closed for user root Dec 31 03:09:07 volumio sudo[21696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 31 03:09:07 volumio sudo[21696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 31 03:09:07 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 03:09:07 volumio volumio[1347]: info: MPD Permissions set Dec 31 03:09:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 31 03:09:07 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 31 03:09:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 31 03:09:07 volumio systemd[1]: mpd.service: Consumed 7.426s CPU time. Dec 31 03:09:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 31 03:09:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 31 03:09:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 31 03:09:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 31 03:09:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 31 03:09:07 volumio sudo[21708]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 31 03:09:07 volumio sudo[21708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 31 03:09:07 volumio sudo[21708]: pam_unix(sudo:session): session closed for user root Dec 31 03:09:08 volumio mpd[21711]: 2025-12-31T03:09:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 31 03:09:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 31 03:09:08 volumio sudo[21696]: pam_unix(sudo:session): session closed for user root Dec 31 03:09:08 volumio volumio[1347]: error: updateQueue error: null Dec 31 03:09:11 volumio sudo[21723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 31 03:09:11 volumio sudo[21723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 31 03:09:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 31 03:09:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 31 03:09:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 31 03:09:12 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 31 03:09:12 volumio sudo[21723]: pam_unix(sudo:session): session closed for user root Dec 31 03:09:12 volumio volumio[1347]: info: Successfully started MPD Monitor Dec 31 03:09:12 volumio mpd_monitor.sh[21725]: MPD Monitor Service: Starting MPD Monitor Service Dec 31 03:09:19 volumio volumio[1347]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::volumioGetState Dec 31 03:09:19 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:09:19 volumio volumio[1347]: info: Restoring Previous Volume level: 100 false true Dec 31 03:09:19 volumio volumio[1347]: info: VolumeController::SetAlsaVolume100 Dec 31 03:09:19 volumio volumio[1347]: info: Volume configurations have been set Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 31 03:09:19 volumio volumio[1347]: info: Updating Volume Controller Parameters: Device: 0,1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 31 03:09:19 volumio volumio[1347]: info: Disabling external Volume Control Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:19 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:09:19 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:09:19 volumio volumio[1347]: info: FusionDsp - Volumio is not playing Dec 31 03:09:19 volumio volumio[1347]: info: FusionDsp - Clipped samples monitor stopped Dec 31 03:09:19 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:09:19 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:09:19 volumio volumio[1347]: info: FusionDsp - Volumio is not playing Dec 31 03:09:19 volumio volumio[1347]: info: FusionDsp - Clipped samples monitor stopped Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 31 03:09:19 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 31 03:09:23 volumio volumio[1347]: info: VolumeController::SetAlsaVolume100 Dec 31 03:09:23 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:09:23 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:09:23 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:09:23 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:09:23 volumio volumio[1347]: info: FusionDsp - Volumio is not playing Dec 31 03:09:23 volumio volumio[1347]: info: FusionDsp - Clipped samples monitor stopped Dec 31 03:09:34 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 31 03:09:34 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 31 03:09:37 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:50 volumio volumio[1347]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Dec 31 03:09:50 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Dec 31 03:09:50 volumio volumio[1347]: info: FusionDsp - Ok! Convolution files exist Dec 31 03:09:50 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:50 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:50 volumio volumio[1347]: info: FusionDsp - State4Clipping saved: { Dec 31 03:09:50 volumio volumio[1347]: "crossfeed": "None", Dec 31 03:09:50 volumio volumio[1347]: "monooutput": false, Dec 31 03:09:50 volumio volumio[1347]: "loudness": false, Dec 31 03:09:50 volumio volumio[1347]: "loudnessthreshold": 50, Dec 31 03:09:50 volumio volumio[1347]: "leftlevel": 0, Dec 31 03:09:50 volumio volumio[1347]: "rightlevel": 0, Dec 31 03:09:50 volumio volumio[1347]: "delay": 0, Dec 31 03:09:50 volumio volumio[1347]: "delayscope": "None", Dec 31 03:09:50 volumio volumio[1347]: "muteleft": false, Dec 31 03:09:50 volumio volumio[1347]: "muteright": false, Dec 31 03:09:50 volumio volumio[1347]: "ldistance": 0, Dec 31 03:09:50 volumio volumio[1347]: "rdistance": 0, Dec 31 03:09:50 volumio volumio[1347]: "permutchannel": false Dec 31 03:09:50 volumio volumio[1347]: } Dec 31 03:09:50 volumio volumio[1347]: info: FusionDsp - Test Eq1|Conv|L/data/INTERNAL/FusionDsp/filters/impulse_l_krowat.txt|0|Eq2|Conv|R/data/INTERNAL/FusionDsp/filters/impulse_r_krowat.txt|0| Dec 31 03:09:50 volumio volumio[1347]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 31 03:09:50 volumio volumio[1347]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 31 03:09:50 volumio volumio[1347]: info: camilladsp stopping service pid 20904... Dec 31 03:09:50 volumio volumio[1347]: info: camilladsp service terminated, instance 1 Dec 31 03:09:50 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:50 volumio volumio[1347]: info: camilladsp service started and running in background, instance 1 Dec 31 03:09:50 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:50 volumio volumio[1347]: info: CoreCommandRouter::volumioPause Dec 31 03:09:50 volumio volumio[1347]: info: CoreStateMachine::pause Dec 31 03:09:51 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:51 volumio volumio[1347]: warn: FusionDsp - Monitor WebSocket not open, cannot send ResetClippedSamples Dec 31 03:09:51 volumio volumio[1347]: warn: FusionDsp - aplay stderr: Playing WAVE '/data/plugins/audio_interface/fusiondsp/testclipping/testclipping.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 31 03:09:51 volumio volumio[1347]: info: FusionDsp - aplay stdout: Dec 31 03:09:51 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:51 volumio volumio[1347]: info: CoreCommandRouter::Close All Modals sent Dec 31 03:09:52 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:52 volumio volumio[1347]: info: CoreCommandRouter::Close All Modals sent Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 0 1.81 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 1 2.65 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 2 2.78 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 3 3.01 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 4 2 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 5 3 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 6 2.76 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 7 2.32 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 8 1.23 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 9 2.17 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 10 3.21 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 11 2.25 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 12 3.28 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - clipping detection gives values in line 13 3.08 Dec 31 03:09:58 volumio volumio[1347]: info: FusionDsp - Restored State4Clipping: { Dec 31 03:09:58 volumio volumio[1347]: "crossfeed": "None", Dec 31 03:09:58 volumio volumio[1347]: "monooutput": false, Dec 31 03:09:58 volumio volumio[1347]: "loudness": false, Dec 31 03:09:58 volumio volumio[1347]: "loudnessthreshold": 50, Dec 31 03:09:58 volumio volumio[1347]: "leftlevel": 0, Dec 31 03:09:58 volumio volumio[1347]: "rightlevel": 0, Dec 31 03:09:58 volumio volumio[1347]: "delay": 0, Dec 31 03:09:58 volumio volumio[1347]: "delayscope": "None", Dec 31 03:09:58 volumio volumio[1347]: "muteleft": false, Dec 31 03:09:58 volumio volumio[1347]: "muteright": false, Dec 31 03:09:58 volumio volumio[1347]: "ldistance": 0, Dec 31 03:09:58 volumio volumio[1347]: "rdistance": 0, Dec 31 03:09:58 volumio volumio[1347]: "permutchannel": false Dec 31 03:09:58 volumio volumio[1347]: } Dec 31 03:09:58 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:09:58 volumio volumio[1347]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 31 03:09:58 volumio volumio[1347]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 31 03:09:58 volumio volumio[1347]: info: CoreCommandRouter::Close All Modals sent Dec 31 03:10:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 31 03:10:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 31 03:10:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 31 03:10:05 volumio volumio[1347]: info: VolumeController::SetAlsaVolume80 Dec 31 03:10:05 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:05 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:05 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:05 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:05 volumio volumio[1347]: info: FusionDsp - Volumio is not playing Dec 31 03:10:05 volumio volumio[1347]: info: FusionDsp - Clipped samples monitor stopped Dec 31 03:10:06 volumio volumio[1347]: info: VolumeController::SetAlsaVolume70 Dec 31 03:10:06 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:06 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:06 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:06 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:06 volumio volumio[1347]: info: FusionDsp - Volumio is not playing Dec 31 03:10:06 volumio volumio[1347]: info: FusionDsp - Clipped samples monitor stopped Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::volumioPlay Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::play index undefined Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::startPlaybackTimer Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::clearAddPlayTracks HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand stop Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand stop took 1 milliseconds Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand clear Dec 31 03:10:10 volumio volumio[1347]: info: Dec 31 03:10:10 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Dec 31 03:10:10 volumio volumio[1347]: info: Ignoring MPD Status Update Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand clear took 1 milliseconds Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand add "HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac" Dec 31 03:10:10 volumio volumio[1347]: error: updateQueue error: null Dec 31 03:10:10 volumio volumio[1347]: info: ------------------------------ 2ms Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand add "HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac" took 1 milliseconds Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand play Dec 31 03:10:10 volumio volumio[1347]: info: Dec 31 03:10:10 volumio volumio[1347]: ---------------------------- MPD announces system playlist update Dec 31 03:10:10 volumio volumio[1347]: info: Ignoring MPD Status Update Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand play took 2 milliseconds Dec 31 03:10:10 volumio volumio[1347]: info: ------------------------------ 1ms Dec 31 03:10:10 volumio volumio[1347]: info: Dec 31 03:10:10 volumio volumio[1347]: ---------------------------- MPD announces state update: player Dec 31 03:10:10 volumio volumio[1347]: info: ControllerMpd::getState Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 31 03:10:10 volumio volumio[1347]: info: camilladsp stopping service pid 21959... Dec 31 03:10:10 volumio volumio[1347]: info: camilladsp service terminated, instance 1 Dec 31 03:10:10 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:10:10 volumio volumio[1347]: info: camilladsp service started and running in background, instance 1 Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 31 03:10:10 volumio volumio[1347]: error: FusionDsp - File size not found in array! Dec 31 03:10:10 volumio volumio[1347]: info: Dec 31 03:10:10 volumio volumio[1347]: ---------------------------- MPD announces state update: player Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand status took 72 milliseconds Dec 31 03:10:10 volumio volumio[1347]: info: ControllerMpd::getState Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::parseState Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand status took 4 milliseconds Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::parseState Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Dec 31 03:10:10 volumio volumio[1347]: info: ControllerMpd::pushState Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" Это просто сон.flac","artist":null,"album":null,"uri":"HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac","trackType":"flac"} Dec 31 03:10:10 volumio volumio[1347]: verbose: CURRENT POSITION 0 Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus stop Dec 31 03:10:10 volumio volumio[1347]: info: ------------------------------ 81ms Dec 31 03:10:10 volumio volumio[1347]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 31 03:10:10 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 31 03:10:10 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Dec 31 03:10:10 volumio volumio[1347]: info: ControllerMpd::pushState Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":675,"duration":223,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1819 Kbps","isStreaming":false,"title":" Это просто сон.flac","artist":null,"album":null,"uri":"HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac","trackType":"flac"} Dec 31 03:10:10 volumio volumio[1347]: verbose: CURRENT POSITION 0 Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Dec 31 03:10:10 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:10 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:10 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:10 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:10 volumio volumio[1347]: info: ------------------------------ 31ms Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:10 volumio volumio[1347]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 31 03:10:10 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:10 volumio volumio[1347]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 31 03:10:10 volumio volumio[1347]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 31 03:10:12 volumio volumio[1347]: info: FusionDsp - Clipping Monitor started Dec 31 03:10:12 volumio volumio[1347]: info: FusionDsp - Clipping Monitor started Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::volumioSeek Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::seek Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: info: TRACKBLOCK {"uri":"mnt/HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac","service":"mpd","name":" Это просто сон.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=969&web=//extralarge&path=%2FHDD%2F%D0%9C%D1%83%D0%B7%D1%8B%D0%BA%D0%B0%2FRU%2F%D0%9A%D1%80%D0%B8%D1%81%D1%82%D0%B8%D0%BD%D0%B0%20%D0%9E%D1%80%D0%B1%D0%B0%D0%BA%D0%B0%D0%B9%D1%82%D0%B5%2FMy%20Life&metadata=false","duration":223,"samplerate":"192 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::startPlaybackTimer Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: info: ControllerMpd::seek Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:15 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:15 volumio volumio[1347]: error: null Dec 31 03:10:15 volumio volumio[1347]: info: Dec 31 03:10:15 volumio volumio[1347]: ---------------------------- MPD announces state update: player Dec 31 03:10:15 volumio volumio[1347]: info: ControllerMpd::getState Dec 31 03:10:15 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand status Dec 31 03:10:15 volumio volumio[1347]: info: sendMpdCommand status took 1 milliseconds Dec 31 03:10:15 volumio volumio[1347]: verbose: ControllerMpd::parseState Dec 31 03:10:15 volumio volumio[1347]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 31 03:10:15 volumio volumio[1347]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 31 03:10:15 volumio volumio[1347]: verbose: ControllerMpd::parseTrackInfo Dec 31 03:10:15 volumio volumio[1347]: info: ControllerMpd::pushState Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::servicePushState Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27000,"duration":223,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1548 Kbps","isStreaming":false,"title":" Это просто сон.flac","artist":null,"album":null,"uri":"HDD/Музыка/RU/Кристина Орбакайте/My Life/ Это просто сон.flac","trackType":"flac"} Dec 31 03:10:15 volumio volumio[1347]: verbose: CURRENT POSITION 0 Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::syncState stateService play Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::syncState currentStatus play Dec 31 03:10:15 volumio volumio[1347]: info: Received an update from plugin. extracting info from payload Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:15 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:15 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:15 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:15 volumio volumio[1347]: info: ------------------------------ 37ms Dec 31 03:10:15 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:15 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:16 volumio volumio[1347]: info: VolumeController::SetAlsaVolume85 Dec 31 03:10:16 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:16 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:16 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:16 volumio volumio[1347]: info: VolumeController::SetAlsaVolume90 Dec 31 03:10:16 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:16 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:16 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:16 volumio volumio[1347]: info: VolumeController::SetAlsaVolume100 Dec 31 03:10:16 volumio volumio[1347]: info: CoreStateMachine::pushState Dec 31 03:10:16 volumio volumio[1347]: info: CorePlayQueue::getTrack 0 Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 03:10:16 volumio volumio[1347]: info: CoreCommandRouter::volumioPushState Dec 31 03:10:16 volumio volumio[1347]: info: FusionDsp - Volumio is playing Dec 31 03:10:27 volumio volumio[1347]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 31 03:10:27 volumio volumio[1347]: info: CURURI: artists:// Dec 31 03:10:27 volumio volumio[1347]: info: Preload queue cleared Dec 31 03:10:31 volumio volumio[1347]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 31 03:10:31 volumio volumio[1347]: TypeError: Cannot read properties of null (reading 'slice') Dec 31 03:10:31 volumio volumio[1347]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 31 03:10:31 volumio volumio[1347]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 31 03:10:31 volumio volumio[1347]: at Object.onceWrapper (node:events:629:26) Dec 31 03:10:31 volumio volumio[1347]: at ClientRequest.emit (node:events:514:28) Dec 31 03:10:31 volumio volumio[1347]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 31 03:10:31 volumio volumio[1347]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 31 03:10:31 volumio volumio[1347]: at TLSSocket.socketOnData (node:_http_client:535:22) Dec 31 03:10:31 volumio volumio[1347]: at TLSSocket.emit (node:events:514:28) Dec 31 03:10:31 volumio volumio[1347]: at addChunk (node:internal/streams/readable:343:12) Dec 31 03:10:31 volumio volumio[1347]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 31 03:10:31 volumio volumio[1347]: at Readable.push (node:internal/streams/readable:253:10) Dec 31 03:10:31 volumio volumio[1347]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Dec 31 03:10:31 volumio volumio[1347]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 31 03:10:31 volumio sudo[22224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-31 03:09' Dec 31 03:10:31 volumio sudo[22224]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"