-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sun 2025-02-16 18:42:55 MSK. -- Feb 16 18:42:47 music systemd-timedated[999]: Changed local time to Sun Feb 16 18:42:47 2025 Feb 16 18:42:47 music sudo[997]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:47 music volumio-time-update[590]: volumio-time-update-util: System time updated successfully. Feb 16 18:42:47 music systemd[1]: Starting Daily apt download activities... Feb 16 18:42:47 music systemd[1]: Starting Daily man-db regeneration... Feb 16 18:42:47 music systemd[1]: Started Volumio Time Update Utility. Feb 16 18:42:47 music ntpd[795]: Listen normally on 3 wlan0 10.10.1.144:123 Feb 16 18:42:47 music ntpd[795]: new interface(s) found: waking up resolver Feb 16 18:42:47 music systemd[1]: apt-daily.service: Succeeded. Feb 16 18:42:47 music systemd[1]: Started Daily apt download activities. Feb 16 18:42:47 music systemd[1]: Starting Daily apt upgrade and clean activities... Feb 16 18:42:47 music systemd[1]: man-db.service: Succeeded. Feb 16 18:42:47 music systemd[1]: Started Daily man-db regeneration. Feb 16 18:42:47 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:47 music volumio[975]: info: ----- Volumio3 ---- Feb 16 18:42:47 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:47 music volumio[975]: info: ----- System startup ---- Feb 16 18:42:47 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:47 music systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 16 18:42:47 music systemd[1]: Started Daily apt upgrade and clean activities. Feb 16 18:42:48 music nmbd[754]: [2025/02/16 18:42:48.172248, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 18:42:48 music systemd[1]: Started Samba NMB Daemon. Feb 16 18:42:48 music nmbd[754]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 16 18:42:48 music systemd[1]: Starting Samba Winbind Daemon... Feb 16 18:42:48 music volumio[975]: info: MYVOLUMIO Environment detected Feb 16 18:42:48 music winbindd[1046]: [2025/02/16 18:42:48.429867, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 16 18:42:48 music winbindd[1046]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 16 18:42:48 music volumio[975]: info: Plugin folders cleanup Feb 16 18:42:48 music volumio[975]: info: Scanning into folder /volumio/app/plugins/ Feb 16 18:42:48 music volumio[975]: info: Scanning category audio_interface Feb 16 18:42:48 music volumio[975]: info: Scanning category miscellanea Feb 16 18:42:48 music volumio[975]: info: Scanning category music_service Feb 16 18:42:48 music volumio[975]: info: Scanning category plugins.json Feb 16 18:42:48 music volumio[975]: info: Scanning category system_controller Feb 16 18:42:48 music volumio[975]: info: Scanning category user_interface Feb 16 18:42:48 music volumio[975]: info: Scanning into folder /data/plugins/ Feb 16 18:42:48 music volumio[975]: info: Scanning category audio_interface Feb 16 18:42:48 music volumio[975]: info: Plugin folders cleanup completed Feb 16 18:42:48 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:48 music volumio[975]: info: ----- Core plugins startup ---- Feb 16 18:42:48 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:48 music volumio[975]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 18:42:48 music volumio[975]: info: Adding plugin upnp to MyMusic Plugins Feb 16 18:42:48 music volumio[975]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 18:42:48 music volumio[975]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 18:42:48 music volumio[975]: info: Loading plugins from folder /data/plugins/ Feb 16 18:42:48 music volumio[975]: info: Loading plugin "system"... Feb 16 18:42:48 music volumio-remote-updater[587]: [2025-02-16 18:42:48] [connect] Successful connection Feb 16 18:42:49 music winbindd[1046]: [2025/02/16 18:42:49.185432, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 18:42:49 music systemd[1]: Started Samba Winbind Daemon. Feb 16 18:42:49 music winbindd[1046]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 16 18:42:49 music volumio[975]: info: Loading plugin "appearance"... Feb 16 18:42:49 music systemd[1]: Starting Samba SMB Daemon... Feb 16 18:42:49 music smbd[1057]: [2025/02/16 18:42:49.591223, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 18:42:49 music systemd[1]: Started Samba SMB Daemon. Feb 16 18:42:49 music smbd[1057]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 16 18:42:49 music systemd[1]: Reached target Multi-User System. Feb 16 18:42:49 music systemd[1]: Reached target Graphical Interface. Feb 16 18:42:49 music systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 16 18:42:49 music systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 16 18:42:49 music systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 16 18:42:49 music systemd[1]: Startup finished in 12.160s (kernel) + 26.949s (userspace) = 39.110s. Feb 16 18:42:50 music volumio[975]: info: Loading plugin "network"... Feb 16 18:42:50 music volumio[975]: info: Refreshing Cached IP Addresses Feb 16 18:42:50 music sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 18:42:50 music sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:50 music sudo[1070]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:50 music sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 18:42:50 music sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:50 music sudo[1072]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:50 music volumio[975]: info: Loading plugin "services"... Feb 16 18:42:50 music volumio[975]: info: Loading plugin "alsa_controller"... Feb 16 18:42:50 music sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 18:42:50 music sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:50 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 18:42:50 music volumio[975]: info: Loading plugin "wizard"... Feb 16 18:42:50 music volumio[975]: info: Loading plugin "networkfs"... Feb 16 18:42:50 music volumio[975]: info: Starting Udev Watcher for removable devices Feb 16 18:42:50 music sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=0707T0palsky1972,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.1.201/archive\\Музыка /mnt/NAS/NAS Feb 16 18:42:50 music sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:50 music sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=0707T0palsky1972,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.1.201/elements/music /mnt/NAS/Qnap-elements-music Feb 16 18:42:50 music sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:50 music volumio[975]: info: Ignoring mount for partition: boot Feb 16 18:42:50 music volumio[975]: info: Ignoring mount for partition: volumio Feb 16 18:42:50 music volumio[975]: info: Ignoring mount for partition: volumio_data Feb 16 18:42:50 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 18:42:50 music volumio[975]: info: Loading plugin "volumio_command_line_client"... Feb 16 18:42:50 music volumio[975]: info: Loading plugin "upnp"... Feb 16 18:42:50 music volumio[975]: info: [1739720570564] Starting Upmpd Daemon Feb 16 18:42:50 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 18:42:50 music volumio[975]: info: Loading plugin "my_music"... Feb 16 18:42:50 music volumio[975]: info: Loading plugin "mpd"... Feb 16 18:42:50 music kernel: Key type cifs.spnego registered Feb 16 18:42:50 music kernel: Key type cifs.idmap registered Feb 16 18:42:50 music kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 16 18:42:50 music kernel: CIFS: Attempting to mount //10.10.1.201/elements/music Feb 16 18:42:50 music kernel: cryptd: max_cpu_qlen set to 1000 Feb 16 18:42:50 music volumio[975]: info: Loading plugin "upnp_browser"... Feb 16 18:42:51 music volumio[975]: info: Loading plugin "alarm-clock"... Feb 16 18:42:51 music sudo[1100]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:51 music volumio[975]: info: Loading plugin "airplay_emulation"... Feb 16 18:42:51 music kernel: CIFS: Attempting to mount //10.10.1.201/archive/Музыка Feb 16 18:42:51 music volumio[975]: info: Starting Shairport Sync Feb 16 18:42:51 music volumio[975]: info: Loading plugin "last_100"... Feb 16 18:42:51 music volumio[975]: info: Loading plugin "webradio"... Feb 16 18:42:51 music volumio[975]: info: Loading plugin "i2s_dacs"... Feb 16 18:42:51 music sudo[1098]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:51 music volumio[975]: info: I2S DAC not set, start Auto-detection Feb 16 18:42:51 music volumio[975]: info: Loading plugin "volumiodiscovery"... Feb 16 18:42:51 music volumio[975]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 18:42:51 music volumio[975]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 18:42:51 music node[975]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 18:42:51 music volumio[975]: *** WARNING *** For more information see Feb 16 18:42:51 music volumio[975]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 18:42:51 music volumio[975]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 18:42:51 music volumio[975]: *** WARNING *** For more information see Feb 16 18:42:51 music node[975]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 18:42:51 music node[975]: *** WARNING *** For more information see Feb 16 18:42:51 music node[975]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 18:42:51 music node[975]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 18:42:51 music node[975]: *** WARNING *** For more information see Feb 16 18:42:51 music volumio[975]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 18:42:51 music volumio[975]: info: Discovery: Started advertising with name: music Feb 16 18:42:51 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 18:42:51 music volumio[975]: info: Loading plugin "outputs"... Feb 16 18:42:51 music volumio[975]: info: Loading plugin "albumart"... Feb 16 18:42:51 music volumio[975]: info: Plugin example_plugin is not enabled Feb 16 18:42:51 music volumio[975]: info: Loading plugin "inputs"... Feb 16 18:42:51 music volumio[975]: info: Loading plugin "updater_comm"... Feb 16 18:42:51 music volumio[975]: info: Plugin mpdemulation is not enabled Feb 16 18:42:51 music volumio[975]: info: Loading plugin "rest_api"... Feb 16 18:42:51 music volumio[975]: Forking 3 albumart workers Feb 16 18:42:51 music volumio[975]: info: Loading plugin "websocket"... Feb 16 18:42:51 music volumio[975]: info: Starting Socket.io Server version 2.3.0 Feb 16 18:42:51 music volumio[975]: info: Loading plugin "fusiondsp"... Feb 16 18:42:52 music volumio[975]: info: Applying required configuration parameters for plugin fusiondsp Feb 16 18:42:52 music volumio[975]: Starting albumart workers Feb 16 18:42:52 music volumio[975]: Starting albumart workers Feb 16 18:42:52 music volumio[975]: Starting albumart workers Feb 16 18:42:52 music volumio[975]: info: Loading i18n strings for locale ru Feb 16 18:42:52 music volumio[975]: Updating browse sources language Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::initPlayerControls Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 18:42:52 music volumio[975]: Express server listening on port 3000 Feb 16 18:42:52 music volumio[975]: [Metrics] WebUI: 5s 893.01ms Feb 16 18:42:52 music volumio[975]: info: CoreStateMachine::resetVolumioState Feb 16 18:42:52 music volumio[975]: info: CoreStateMachine::getcurrentVolume Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 18:42:52 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:52 music volumio[975]: info: Volumio Network Manager: Network status updated: 2 Feb 16 18:42:52 music volumio-remote-updater[587]: [2025-02-16 18:42:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739720568 101 Feb 16 18:42:52 music volumio[975]: 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: 1 Feb 16 18:42:52 music volumio[975]: Unable to parse: Feb 16 18:42:52 music volumio[975]: Simple mixer control 'Cambridge Audio Audio 2.0 Output Playback S',0 Feb 16 18:42:52 music volumio[975]: Capabilities: pswitch Feb 16 18:42:52 music volumio[975]: Playback channels: Front Left - Front Right Feb 16 18:42:52 music volumio[975]: Mono: Feb 16 18:42:52 music volumio[975]: Front Left: Playback [on] Feb 16 18:42:52 music volumio[975]: Front Right: Playback [on] Feb 16 18:42:52 music volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 16 18:42:52 music volumio[975]: info: Reloading queue from file Feb 16 18:42:53 music volumio[975]: Unable to parse: Feb 16 18:42:53 music volumio[975]: Simple mixer control 'Cambridge Audio Audio 2.0 Output Playback S',0 Feb 16 18:42:53 music volumio[975]: Capabilities: pswitch Feb 16 18:42:53 music volumio[975]: Playback channels: Front Left - Front Right Feb 16 18:42:53 music volumio[975]: Mono: Feb 16 18:42:53 music volumio[975]: Front Left: Playback [on] Feb 16 18:42:53 music volumio[975]: Front Right: Playback [on] Feb 16 18:42:53 music volumio[975]: info: VolumeController:: Volume=undefined Mute =false Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::pushState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioPushState Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::updateTrackBlock Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrackBlock Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::setRepeat null single undefined Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::pushState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioPushState Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::setRandom null Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::pushState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioPushState Feb 16 18:42:53 music volumio[975]: info: Setting Device type: Raspberry PI Feb 16 18:42:53 music volumio[975]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 16 18:42:53 music volumio[975]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Feb 16 18:42:53 music volumio[975]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 16 18:42:53 music volumio[975]: Unable to parse: Feb 16 18:42:53 music volumio[975]: Simple mixer control 'Cambridge Audio Audio 2.0 Output Playback S',0 Feb 16 18:42:53 music volumio[975]: Capabilities: pswitch Feb 16 18:42:53 music volumio[975]: Playback channels: Front Left - Front Right Feb 16 18:42:53 music volumio[975]: Mono: Feb 16 18:42:53 music volumio[975]: Front Left: Playback [on] Feb 16 18:42:53 music volumio[975]: Front Right: Playback [on] Feb 16 18:42:53 music volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 16 18:42:53 music volumio[975]: info: Completed loading Core Plugins Feb 16 18:42:53 music volumio[975]: info: Preparing to generate the ALSA configuration file Feb 16 18:42:53 music volumio[975]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 16 18:42:53 music volumio[975]: info: Reading ALSA contributions from plugins. Feb 16 18:42:53 music volumio[975]: Unable to parse: Feb 16 18:42:53 music volumio[975]: Simple mixer control 'Cambridge Audio Audio 2.0 Output Playback S',0 Feb 16 18:42:53 music volumio[975]: Capabilities: pswitch Feb 16 18:42:53 music volumio[975]: Playback channels: Front Left - Front Right Feb 16 18:42:53 music volumio[975]: Mono: Feb 16 18:42:53 music volumio[975]: Front Left: Playback [on] Feb 16 18:42:53 music volumio[975]: Front Right: Playback [on] Feb 16 18:42:53 music volumio[975]: info: VolumeController:: Volume=undefined Mute =false Feb 16 18:42:53 music volumio[975]: info: CoreStateMachine::pushState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioPushState Feb 16 18:42:53 music volumio[975]: info: Asound.conf file unchanged, so no further update is needed Feb 16 18:42:53 music volumio[975]: info: Output device has changed, restarting MPD Feb 16 18:42:53 music sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 18:42:53 music sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music volumio[975]: info: Output device has changed, restarting Shairport Sync Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music sudo[1210]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 18:42:53 music sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music systemd[1]: Stopping Music Player Daemon... Feb 16 18:42:53 music volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 18:42:53 music volumio[975]: info: ___________ START PLUGINS ___________ Feb 16 18:42:53 music volumio[975]: info: ControllerMpd::onStart: Initializing MPD Feb 16 18:42:53 music volumio[975]: info: Creating MPD Configuration file Feb 16 18:42:53 music sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 18:42:53 music volumio[975]: info: [1739720573203] CoreMusicLibrary::Adding element Медиа-серверы Feb 16 18:42:53 music sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 18:42:53 music sudo[1219]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 18:42:53 music sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music systemd[1]: mpd.service: Succeeded. Feb 16 18:42:53 music systemd[1]: Stopped Music Player Daemon. Feb 16 18:42:53 music volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 18:42:53 music volumio[975]: info: [1739720573298] CoreMusicLibrary::Adding element Last_100 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 18:42:53 music volumio[975]: info: [1739720573300] CoreMusicLibrary::Adding element Webradio Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 18:42:53 music volumio[975]: info: Initializing BBC Radios Feb 16 18:42:53 music systemd[1]: Starting Music Player Daemon... Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: Loading i18n strings for locale ru Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: FusionDsp - mixtype--------------------- Hardware Feb 16 18:42:53 music volumio[975]: info: Volumio Calling Home Feb 16 18:42:53 music sudo[1227]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 18:42:53 music sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music sudo[1227]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music volumio[975]: info: Preparing to generate the ALSA configuration file Feb 16 18:42:53 music volumio[975]: info: Discovery: adding 653b47c9-9457-485e-9978-748194845610 Feb 16 18:42:53 music volumio[975]: info: Discovery: Found device music Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioGetState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music volumio[975]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 16 18:42:53 music volumio[975]: info: Reading ALSA contributions from plugins. Feb 16 18:42:53 music volumio[975]: info: MPD Permissions set Feb 16 18:42:53 music volumio[975]: info: MPD Permissions set Feb 16 18:42:53 music volumio[975]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: Starting Shairport Sync Feb 16 18:42:53 music volumio[975]: info: Starting Shairport Sync Feb 16 18:42:53 music volumio[975]: info: Starting Shairport Sync Feb 16 18:42:53 music sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 18:42:53 music sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music volumio[975]: info: Asound.conf file unchanged, so no further update is needed Feb 16 18:42:53 music volumio[975]: info: Output device has changed, restarting MPD Feb 16 18:42:53 music sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 18:42:53 music sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 18:42:53 music systemd[1]: shairport-sync.service: Succeeded. Feb 16 18:42:53 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 18:42:53 music sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 18:42:53 music volumio[975]: info: Output device has changed, restarting Shairport Sync Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 18:42:53 music sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 18:42:53 music sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music sudo[1263]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music volumio[975]: info: MPD Permissions set Feb 16 18:42:53 music sudo[1255]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::volumioGetState Feb 16 18:42:53 music volumio[975]: info: CorePlayQueue::getTrack 0 Feb 16 18:42:53 music systemd[1]: shairport-sync.service: Succeeded. Feb 16 18:42:53 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 16 18:42:53 music systemd[1]: mpd.service: Succeeded. Feb 16 18:42:53 music systemd[1]: Stopped Music Player Daemon. Feb 16 18:42:53 music volumio[975]: info: Shairport-Sync Started Feb 16 18:42:53 music systemd[1]: Starting Music Player Daemon... Feb 16 18:42:53 music volumio[975]: Error adding Membership: Error: addMembership EINVAL Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music sudo[1257]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music sudo[1260]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 18:42:53 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:53 music volumio[975]: info: Shairport-Sync Started Feb 16 18:42:53 music volumio[975]: info: Shairport-Sync Started Feb 16 18:42:53 music volumio[975]: info: Starting Shairport Sync Feb 16 18:42:53 music volumio[975]: info: Volumio called home Feb 16 18:42:53 music sudo[1274]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 18:42:53 music sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music sudo[1274]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 18:42:53 music sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:53 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 18:42:53 music systemd[1]: shairport-sync.service: Succeeded. Feb 16 18:42:53 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 18:42:53 music sudo[1283]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:53 music volumio[975]: info: Shairport-Sync Started Feb 16 18:42:54 music sudo[1084]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:54 music mpd[1285]: Feb 16 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 18:42:54 music systemd[1]: Started Music Player Daemon. Feb 16 18:42:54 music sudo[1267]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:54 music sudo[1221]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:54 music sudo[1212]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:54 music volumio[975]: info: Completed starting Core Plugins Feb 16 18:42:54 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:54 music volumio[975]: info: ----- MyVolumio plugins startup ---- Feb 16 18:42:54 music volumio[975]: info: ------------------------------------------- Feb 16 18:42:54 music volumio[975]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 18:42:54 music volumio[975]: error: MPD error: The expression evaluated to a falsy value: Feb 16 18:42:54 music volumio[975]: assert.ok(self.idling) Feb 16 18:42:54 music volumio[975]: error: The expression evaluated to a falsy value: Feb 16 18:42:54 music volumio[975]: assert.ok(self.idling) Feb 16 18:42:54 music volumio[975]: error: MPD error: The expression evaluated to a falsy value: Feb 16 18:42:54 music volumio[975]: assert.ok(self.idling) Feb 16 18:42:54 music volumio[975]: error: The expression evaluated to a falsy value: Feb 16 18:42:54 music volumio[975]: assert.ok(self.idling) Feb 16 18:42:54 music volumio[975]: info: MPD running with PID1285 Feb 16 18:42:54 music volumio[975]: ,establishing connection Feb 16 18:42:54 music volumio[975]: error: updateQueue error: null Feb 16 18:42:54 music volumio[975]: error: updateQueue error: null Feb 16 18:42:55 music volumio[975]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Feb 16 18:42:55 music volumio[975]: info: camilladsp service started and running in background, instance 1 Feb 16 18:42:55 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 18:42:55 music volumio[975]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 16 18:42:55 music volumio[975]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 16 18:42:55 music volumio[975]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 16 18:42:55 music volumio[975]: info: FusionDsp loaded Feb 16 18:42:55 music volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 18:42:55 music sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 16 18:42:55 music sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 18:42:55 music volumio[975]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 16 18:42:55 music volumio[975]: info: Adding Signal Path Element [object Object] Feb 16 18:42:55 music volumio[975]: info: Adding fusiondspeq DSP Signal Path Element Feb 16 18:42:55 music volumio[975]: info: FusionDsp - ---- installed callbackRead Feb 16 18:42:55 music volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 18:42:55 music volumio[975]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 16 18:42:55 music volumio[975]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 16 18:42:55 music volumio[975]: at onErrorNT (internal/child_process.js:465:16) Feb 16 18:42:55 music volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 16 18:42:55 music volumio[975]: errno: -2, Feb 16 18:42:55 music volumio[975]: code: 'ENOENT', Feb 16 18:42:55 music volumio[975]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 16 18:42:55 music volumio[975]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 16 18:42:55 music volumio[975]: spawnargs: [ Feb 16 18:42:55 music volumio[975]: '-p', Feb 16 18:42:55 music volumio[975]: 9876, Feb 16 18:42:55 music volumio[975]: '-o', Feb 16 18:42:55 music volumio[975]: '/tmp/camilladsp.log', Feb 16 18:42:55 music volumio[975]: '-l', Feb 16 18:42:55 music volumio[975]: 'warn', Feb 16 18:42:55 music volumio[975]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 16 18:42:55 music volumio[975]: ] Feb 16 18:42:55 music volumio[975]: } Feb 16 18:42:55 music volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 18:42:55 music systemd[1]: Started FusionDsp Daemon. Feb 16 18:42:55 music sudo[1305]: pam_unix(sudo:session): session closed for user root Feb 16 18:42:55 music volumio[1307]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Feb 16 18:42:55 music systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Feb 16 18:42:55 music systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 16 18:42:55 music systemd[1]: systemd-fsckd.service: Succeeded. Feb 16 18:42:55 music sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 18:41 Feb 16 18:42:55 music sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"