-- Logs begin at Tue 2025-10-28 12:08:19 MSK, end at Tue 2025-10-28 13:28:04 MSK. --
Oct 28 13:26:03 volumio volumio[862]: info: Selecting previously unselected package libisl19:armhf.
Oct 28 13:26:03 volumio volumio[862]: info: Preparing to unpack .../01-libisl19_0.20-2_armhf.deb ...
Oct 28 13:26:04 volumio volumio[862]: info: Unpacking libisl19:armhf (0.20-2) ...
Oct 28 13:26:05 volumio volumio[862]: verbose: New Socket.io Connection to 192.168.0.67 from 192.168.0.200 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 28 13:26:05 volumio volumio[862]: info: Received Get System Info
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 28 13:26:05 volumio volumio[862]: info: Discovery: Getting this device information
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState
Oct 28 13:26:05 volumio volumio[862]: info: Listing playlists
Oct 28 13:26:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 28 13:26:07 volumio volumio[862]: info: Selecting previously unselected package libmpfr6:armhf.
Oct 28 13:26:07 volumio volumio[862]: info: Preparing to unpack .../02-libmpfr6_4.0.2-1_armhf.deb ...
Oct 28 13:26:07 volumio volumio[862]: info: Unpacking libmpfr6:armhf (4.0.2-1) ...
Oct 28 13:26:08 volumio volumio[862]: info: Selecting previously unselected package libmpc3:armhf.
Oct 28 13:26:08 volumio volumio[862]: info: Preparing to unpack .../03-libmpc3_1.1.0-1_armhf.deb ...
Oct 28 13:26:08 volumio volumio[862]: info: Unpacking libmpc3:armhf (1.1.0-1) ...
Oct 28 13:26:09 volumio volumio[862]: info: Selecting previously unselected package cpp-8.
Oct 28 13:26:09 volumio volumio[862]: info: Preparing to unpack .../04-cpp-8_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:09 volumio volumio[862]: info: Unpacking cpp-8 (8.3.0-6+rpi1) ...
Oct 28 13:26:22 volumio volumio[862]: info: Selecting previously unselected package cpp.
Oct 28 13:26:22 volumio volumio[862]: info: Preparing to unpack .../05-cpp_4%3a8.3.0-1+rpi2_armhf.deb ...
Oct 28 13:26:22 volumio volumio[862]: info: Unpacking cpp (4:8.3.0-1+rpi2) ...
Oct 28 13:26:23 volumio volumio[862]: info: Selecting previously unselected package libcc1-0:armhf.
Oct 28 13:26:23 volumio volumio[862]: info: Preparing to unpack .../06-libcc1-0_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:23 volumio volumio[862]: info: Unpacking libcc1-0:armhf (8.3.0-6+rpi1) ...
Oct 28 13:26:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 28 13:26:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 28 13:26:25 volumio volumio[862]: info: Selecting previously unselected package libasan5:armhf.
Oct 28 13:26:25 volumio volumio[862]: info: Preparing to unpack .../07-libasan5_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:25 volumio volumio[862]: info: Unpacking libasan5:armhf (8.3.0-6+rpi1) ...
Oct 28 13:26:26 volumio volumio[862]: info: Selecting previously unselected package libubsan1:armhf.
Oct 28 13:26:26 volumio volumio[862]: info: Preparing to unpack .../08-libubsan1_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:26 volumio volumio[862]: info: Unpacking libubsan1:armhf (8.3.0-6+rpi1) ...
Oct 28 13:26:27 volumio volumio[862]: info: Selecting previously unselected package libgcc-8-dev:armhf.
Oct 28 13:26:27 volumio volumio[862]: info: Preparing to unpack .../09-libgcc-8-dev_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:28 volumio volumio[862]: info: Unpacking libgcc-8-dev:armhf (8.3.0-6+rpi1) ...
Oct 28 13:26:36 volumio volumio[862]: info: Enabling plugin fusiondsp
Oct 28 13:26:36 volumio volumio[862]: info: Loading plugin "fusiondsp"...
Oct 28 13:26:44 volumio volumio[862]: info: Preparing to generate the ALSA configuration file
Oct 28 13:26:44 volumio volumio[862]: info: Selecting previously unselected package gcc-8.
Oct 28 13:26:44 volumio volumio[862]: info: Preparing to unpack .../10-gcc-8_8.3.0-6+rpi1_armhf.deb ...
Oct 28 13:26:44 volumio volumio[862]: info: Unpacking gcc-8 (8.3.0-6+rpi1) ...
Oct 28 13:26:45 volumio volumio[862]: info: Asound.conf file unchanged, so no further update is needed
Oct 28 13:26:45 volumio volumio[862]: info: Output device has changed, restarting MPD
Oct 28 13:26:45 volumio volumio[862]: info: Output device has changed, restarting Shairport Sync
Oct 28 13:26:45 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 28 13:26:45 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 28 13:26:45 volumio sudo[17537]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 28 13:26:45 volumio sudo[17537]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 28 13:26:45 volumio sudo[17539]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 28 13:26:45 volumio sudo[17539]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 28 13:26:52 volumio sudo[17537]: pam_unix(sudo:session): session closed for user root
Oct 28 13:26:55 volumio volumio-remote-updater[440]: [2025-10-28 13:26:55] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006]
Oct 28 13:26:59 volumio systemd[1]: Stopping Music Player Daemon...
Oct 28 13:26:59 volumio volumio[862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 28 13:26:59 volumio volumio[862]: info: PLUGIN START: fusiondsp
Oct 28 13:26:59 volumio volumio[862]: info: Loading i18n strings for locale ru
Oct 28 13:27:00 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 28 13:27:00 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 28 13:27:00 volumio volumio[862]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 28 13:27:00 volumio volumio[862]: info: Preparing to generate the ALSA configuration file
Oct 28 13:27:00 volumio volumio-remote-updater[440]: [2025-10-28 13:27:00] [connect] Successful connection
Oct 28 13:27:01 volumio volumio[862]: info: Done.
Oct 28 13:27:01 volumio volumio[862]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 28 13:27:01 volumio volumio[862]: info: Reading ALSA contributions from plugins.
Oct 28 13:27:01 volumio volumio[862]: info: MPD Permissions set
Oct 28 13:27:01 volumio volumio-remote-updater[440]: [2025-10-28 13:27:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761647220 101
Oct 28 13:27:02 volumio volumio[862]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2
Oct 28 13:27:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 28 13:27:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 28 13:27:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 28 13:27:13 volumio volumio[862]: info: camilladsp service started and running in background, instance 1
Oct 28 13:27:13 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 28 13:27:14 volumio volumio[862]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Oct 28 13:27:14 volumio volumio[862]: error: FusionDsp -  ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Oct 28 13:27:14 volumio volumio[862]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Oct 28 13:27:14 volumio volumio[862]: info: FusionDsp loaded
Oct 28 13:27:14 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 28 13:27:14 volumio sudo[17607]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 28 13:27:14 volumio sudo[17607]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 28 13:27:15 volumio volumio[862]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 28 13:27:15 volumio volumio[862]: info: Adding Signal Path Element [object Object]
Oct 28 13:27:15 volumio volumio[862]: info: Adding fusiondspeq DSP Signal Path Element
Oct 28 13:27:15 volumio volumio[862]: info: FusionDsp -  ---- installed callbackRead
Oct 28 13:27:15 volumio sudo[17607]: pam_unix(sudo:session): session closed for user root
Oct 28 13:27:15 volumio volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 28 13:27:16 volumio volumio[862]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Oct 28 13:27:16 volumio volumio[862]:     at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Oct 28 13:27:16 volumio volumio[862]:     at onErrorNT (internal/child_process.js:465:16)
Oct 28 13:27:16 volumio volumio[862]:     at processTicksAndRejections (internal/process/task_queues.js:80:21) {
Oct 28 13:27:16 volumio volumio[862]:   errno: -2,
Oct 28 13:27:16 volumio volumio[862]:   code: 'ENOENT',
Oct 28 13:27:16 volumio volumio[862]:   syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Oct 28 13:27:16 volumio volumio[862]:   path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Oct 28 13:27:16 volumio volumio[862]:   spawnargs: [
Oct 28 13:27:16 volumio volumio[862]:     '-p',
Oct 28 13:27:16 volumio volumio[862]:     9876,
Oct 28 13:27:16 volumio volumio[862]:     '-o',
Oct 28 13:27:16 volumio volumio[862]:     '/tmp/camilladsp.log',
Oct 28 13:27:16 volumio volumio[862]:     '-l',
Oct 28 13:27:16 volumio volumio[862]:     'warn',
Oct 28 13:27:16 volumio volumio[862]:     '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Oct 28 13:27:16 volumio volumio[862]:   ]
Oct 28 13:27:16 volumio volumio[862]: }
Oct 28 13:27:16 volumio volumio[862]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 28 13:27:22 volumio mpd_monitor.sh[426]: MPD Monitor Service: MPD Appears to be inactive, restarting
Oct 28 13:27:26 volumio systemd[1]: mpd.service: Succeeded.
Oct 28 13:27:26 volumio systemd[1]: Stopped Music Player Daemon.
Oct 28 13:27:26 volumio systemd[1]: Starting Music Player Daemon...
Oct 28 13:27:28 volumio sudo[17687]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 28 13:27:28 volumio sudo[17687]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 28 13:27:28 volumio sudo[17687]: pam_unix(sudo:session): session closed for user root
Oct 28 13:27:31 volumio volumio-remote-updater[440]: [2025-10-28 13:27:31] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006]
Oct 28 13:27:37 volumio volumio-remote-updater[440]: [2025-10-28 13:27:36] [connect] Successful connection
Oct 28 13:27:43 volumio sudo[17721]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-28 13:26
Oct 28 13:27:43 volumio sudo[17721]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 28 13:27:49 volumio volumio-remote-updater[440]: [2025-10-28 13:27:49] [connect] Successful connection
Oct 28 13:28:04 volumio volumio-remote-updater[440]: [2025-10-28 13:28:04] [connect] Successful connection
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"