-- Logs begin at Thu 2019-02-14 08:11:59 -02, end at Tue 2026-04-14 22:02:57 -03. -- Apr 14 22:01:02 closetotheedge sudo[8421]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:10 closetotheedge sudo[8581]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt autoremove Apr 14 22:01:10 closetotheedge sudo[8581]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 14 22:01:12 closetotheedge nmbd[744]: [2026/04/14 22:01:12.651508, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 14 22:01:12 closetotheedge nmbd[744]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.70 for name WORKGROUP<1d>. Apr 14 22:01:12 closetotheedge nmbd[744]: This response was from IP 192.168.1.146, reporting an IP address of 192.168.1.146. Apr 14 22:01:20 closetotheedge sudo[8581]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:28 closetotheedge sudo[8614]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt autoclean Apr 14 22:01:28 closetotheedge sudo[8614]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 14 22:01:29 closetotheedge sudo[8614]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:40 closetotheedge volumio[846]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 14 22:01:40 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:01:40.004-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:01:43 closetotheedge volumio[846]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 14 22:01:43 closetotheedge volumio[846]: info: FusionDsp - Stopping FusionDsp service Apr 14 22:01:43 closetotheedge volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:01:43 closetotheedge volumio[846]: TypeError: Cannot read property 'stop' of null Apr 14 22:01:43 closetotheedge volumio[846]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Apr 14 22:01:43 closetotheedge volumio[846]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 14 22:01:43 closetotheedge volumio[846]: at /volumio/app/pluginmanager.js:956:12 Apr 14 22:01:43 closetotheedge volumio[846]: at ChildProcess.exithandler (child_process.js:299:7) Apr 14 22:01:43 closetotheedge volumio[846]: at ChildProcess.emit (events.js:315:20) Apr 14 22:01:43 closetotheedge volumio[846]: at maybeClose (internal/child_process.js:1048:16) Apr 14 22:01:43 closetotheedge volumio[846]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Apr 14 22:01:43 closetotheedge volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:01:43 closetotheedge sudo[8708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 22:00 Apr 14 22:01:43 closetotheedge sudo[8708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:43 closetotheedge sudo[8708]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:44 closetotheedge vtcs[1545]: [2026-04-14 22:01:44.662] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 14 22:01:44 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:01:44.662-03:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Apr 14 22:01:44 closetotheedge volumio-remote-updater[626]: [2026-04-14 22:01:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 14 22:01:44 closetotheedge volumio-remote-updater[626]: [2026-04-14 22:01:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 14 22:01:44 closetotheedge qobuz-connect[1504]: 20260414 22:01:44.714 [1504.1504] INFO SampleApp: Socket closed Apr 14 22:01:44 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:01:44.715-03:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:51188->127.0.0.1:3000: read: connection reset by peer" Apr 14 22:01:44 closetotheedge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 14 22:01:44 closetotheedge systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 14 22:01:44 closetotheedge systemd[1]: Started dynamicswap service. Apr 14 22:01:44 closetotheedge systemd[1]: dynamicswap.service: Succeeded. Apr 14 22:01:44 closetotheedge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 14 22:01:44 closetotheedge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 14 22:01:44 closetotheedge systemd[1]: Stopped Volumio Backend Module. Apr 14 22:01:44 closetotheedge systemd[1]: Started Volumio Backend Module. Apr 14 22:01:44 closetotheedge systemd[1]: Started dynamicswap service. Apr 14 22:01:44 closetotheedge systemd[1]: dynamicswap.service: Succeeded. Apr 14 22:01:45 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:01:45.717-03:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Apr 14 22:01:47 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:47 closetotheedge volumio[8722]: info: ----- Volumio3 ---- Apr 14 22:01:47 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:47 closetotheedge volumio[8722]: info: ----- System startup ---- Apr 14 22:01:47 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:49 closetotheedge volumio[8722]: info: MYVOLUMIO Environment detected Apr 14 22:01:49 closetotheedge volumio[8722]: info: Plugin folders cleanup Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning into folder /volumio/app/plugins/ Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category audio_interface Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category miscellanea Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category music_service Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category plugins.json Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category system_controller Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category user_interface Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning into folder /data/plugins/ Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category audio_interface Apr 14 22:01:49 closetotheedge volumio[8722]: info: Scanning category system_hardware Apr 14 22:01:49 closetotheedge volumio[8722]: info: Plugin folders cleanup completed Apr 14 22:01:49 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:49 closetotheedge volumio[8722]: info: ----- Core plugins startup ---- Apr 14 22:01:49 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:49 closetotheedge volumio[8722]: info: Loading plugins from folder /volumio/app/plugins/ Apr 14 22:01:49 closetotheedge volumio[8722]: info: Adding plugin upnp to MyMusic Plugins Apr 14 22:01:49 closetotheedge volumio[8722]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 14 22:01:49 closetotheedge volumio[8722]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 14 22:01:49 closetotheedge volumio[8722]: info: Loading plugins from folder /data/plugins/ Apr 14 22:01:49 closetotheedge volumio[8722]: info: Loading plugin "system"... Apr 14 22:01:49 closetotheedge volumio[8722]: info: Loading plugin "appearance"... Apr 14 22:01:49 closetotheedge volumio-remote-updater[626]: [2026-04-14 22:01:49] [connect] Successful connection Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "network"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Refreshing Cached IP Addresses Apr 14 22:01:50 closetotheedge sudo[8749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 22:01:50 closetotheedge sudo[8749]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:50 closetotheedge sudo[8749]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:50 closetotheedge sudo[8751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:01:50 closetotheedge sudo[8751]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "services"... Apr 14 22:01:50 closetotheedge sudo[8751]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "volumio5onboarding"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "alsa_controller"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "wizard"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "networkfs"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Cannot mount NAS Public at system boot, trial number 1 ,retrying in 5 seconds Apr 14 22:01:50 closetotheedge volumio[8722]: info: Starting Udev Watcher for removable devices Apr 14 22:01:50 closetotheedge volumio[8722]: info: Ignoring mount for partition: boot Apr 14 22:01:50 closetotheedge volumio[8722]: info: Ignoring mount for partition: volumio Apr 14 22:01:50 closetotheedge volumio[8722]: info: Ignoring mount for partition: volumio_data Apr 14 22:01:50 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "volumio_command_line_client"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "upnp"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: [1776214910884] Starting Upmpd Daemon Apr 14 22:01:50 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "my_music"... Apr 14 22:01:50 closetotheedge volumio[8722]: info: Loading plugin "mpd"... Apr 14 22:01:51 closetotheedge volumio[8722]: info: Loading plugin "upnp_browser"... Apr 14 22:01:51 closetotheedge volumio[8722]: info: Starting UPNP Browser Apr 14 22:01:51 closetotheedge volumio[8722]: info: Loading plugin "alarm-clock"... Apr 14 22:01:51 closetotheedge volumio[8722]: info: Loading plugin "airplay_emulation"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "last_100"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "webradio"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "i2s_dacs"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "volumiodiscovery"... Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** For more information see Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:01:52 closetotheedge volumio[8722]: *** WARNING *** For more information see Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** For more information see Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 14 22:01:52 closetotheedge node[8722]: *** WARNING *** For more information see Apr 14 22:01:52 closetotheedge volumio[8722]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 14 22:01:52 closetotheedge volumio[8722]: info: Discovery: Started advertising with name: CloseToTheEdge Apr 14 22:01:52 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "outputs"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "albumart"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Plugin example_plugin is not enabled Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "inputs"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "updater_comm"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Plugin mpdemulation is not enabled Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "rest_api"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "websocket"... Apr 14 22:01:52 closetotheedge volumio[8722]: info: Starting Socket.io Server version 2.3.0 Apr 14 22:01:52 closetotheedge volumio[8722]: info: Loading plugin "fusiondsp"... Apr 14 22:01:52 closetotheedge volumio[8722]: Forking 3 albumart workers Apr 14 22:01:53 closetotheedge volumio[8722]: info: Applying required configuration parameters for plugin fusiondsp Apr 14 22:01:53 closetotheedge volumio[8722]: info: Loading plugin "ir_controller"... Apr 14 22:01:53 closetotheedge volumio[8722]: info: Applying required configuration parameters for plugin ir_controller Apr 14 22:01:53 closetotheedge volumio[8722]: Starting albumart workers Apr 14 22:01:53 closetotheedge volumio[8722]: info: Loading i18n strings for locale en Apr 14 22:01:53 closetotheedge volumio[8722]: Updating browse sources language Apr 14 22:01:53 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:01:53 closetotheedge volumio[8722]: Starting albumart workers Apr 14 22:01:53 closetotheedge volumio[8722]: Starting albumart workers Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::initPlayerControls Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: Express server listening on port 3000 Apr 14 22:01:54 closetotheedge volumio[8722]: [Metrics] WebUI: 7s 921.12ms Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::resetVolumioState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::getcurrentVolume Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 22:01:54 closetotheedge volumio[8722]: info: Volumio Network Manager: Network status updated: 1 Apr 14 22:01:54 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Apr 14 22:01:54 closetotheedge volumio[8722]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.1.64 UA: Mozilla/5.0 (X11; Linux x86_64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 14 22:01:54 closetotheedge volumio[8722]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.1.64 UA: Mozilla/5.0 (X11; Linux x86_64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 14 22:01:54 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Apr 14 22:01:54 closetotheedge volumio[8722]: info: VolumeController:: Volume=28 Mute =false Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::updateTrackBlock Apr 14 22:01:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrackBlock Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:54 closetotheedge volumio[8722]: info: Reloading queue from file Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::setRepeat null single undefined Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::setRandom null Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:01:54 closetotheedge volumio[8722]: info: Setting Device type: Raspberry PI Apr 14 22:01:54 closetotheedge volumio[8722]: info: Completed loading Core Plugins Apr 14 22:01:54 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:01:54 closetotheedge volumio[8722]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 14 22:01:54 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:01:54 closetotheedge volumio[8722]: info: Asound.conf file written Apr 14 22:01:55 closetotheedge sudo[8840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 22:01:55 closetotheedge sudo[8840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge sudo[8840]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:01:55 closetotheedge sudo[8845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:01:55 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:01:55 closetotheedge sudo[8845]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:55 closetotheedge sudo[8845]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge sudo[8847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:01:55 closetotheedge sudo[8847]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge systemd[1]: Stopping Music Player Daemon... Apr 14 22:01:55 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:01:55 closetotheedge volumio[8722]: info: ___________ START PLUGINS ___________ Apr 14 22:01:55 closetotheedge volumio[8722]: info: ControllerMpd::onStart: Initializing MPD Apr 14 22:01:55 closetotheedge volumio[8722]: info: Creating MPD Configuration file Apr 14 22:01:55 closetotheedge sudo[8854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 14 22:01:55 closetotheedge sudo[8854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge sudo[8856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:01:55 closetotheedge sudo[8856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge volumio[8722]: info: [1776214915278] CoreMusicLibrary::Adding element Media Servers Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:01:55 closetotheedge sudo[8859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:01:55 closetotheedge sudo[8859]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge sudo[8856]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge sudo[8854]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge volumio[8722]: info: UPNP Browser: Client initialized successfully Apr 14 22:01:55 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:01:55 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:55 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:01:55 closetotheedge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 14 22:01:55 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:01:55 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:01:55 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:01:55 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:01:55 closetotheedge volumio[8722]: info: [1776214915455] CoreMusicLibrary::Adding element Last_100 Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:01:55 closetotheedge volumio[8722]: info: [1776214915459] CoreMusicLibrary::Adding element Webradio Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:01:55 closetotheedge volumio[8722]: info: Initializing BBC Radios Apr 14 22:01:55 closetotheedge sudo[8866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:01:55 closetotheedge sudo[8866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge sudo[8866]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: Loading i18n strings for locale en Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:55 closetotheedge volumio[8722]: info: FusionDsp - mixtype--------------------- Hardware Apr 14 22:01:55 closetotheedge volumio[8722]: info: Loading i18n strings for locale en Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:01:55 closetotheedge volumio[8722]: info: Volumio Calling Home Apr 14 22:01:55 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:01:55 closetotheedge sudo[8888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Apr 14 22:01:55 closetotheedge sudo[8888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:55 closetotheedge sudo[8888]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:55 closetotheedge volumio[8722]: info: Cannot mount NAS Public at system boot, trial number 2 ,retrying in 5 seconds Apr 14 22:01:55 closetotheedge volumio[8722]: info: Discovery: adding d129deea-03bb-4c0a-8a86-869069192c26 Apr 14 22:01:55 closetotheedge volumio[8722]: info: Discovery: Found device CloseToTheEdge Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:55 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:55 closetotheedge volumio[8722]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 14 22:01:55 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:01:55 closetotheedge volumio[8722]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.1.64 UA: Mozilla/5.0 (X11; Linux x86_64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 14 22:01:55 closetotheedge volumio[8722]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Apr 14 22:01:55 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:01:55 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:01:55 closetotheedge volumio[8722]: info: VolumeController:: Volume=28 Mute =false Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:01:55 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 22:01:56 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetQueue Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreStateMachine::getQueue Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getQueue Apr 14 22:01:56 closetotheedge volumio[8722]: info: Listing playlists Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 22:01:56 closetotheedge volumio[8722]: info: Received Get System Info Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 22:01:56 closetotheedge volumio[8722]: info: Discovery: Getting this device information Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:56 closetotheedge volumio[8722]: info: Volumio called home Apr 14 22:01:56 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:01:56 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:01:56 closetotheedge sudo[8899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:01:56 closetotheedge sudo[8899]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:56 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:01:56 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:01:56 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:01:56 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:56 closetotheedge sudo[8902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:01:56 closetotheedge sudo[8902]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:01:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:01:56 closetotheedge sudo[8904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:01:56 closetotheedge sudo[8904]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:56 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:56 closetotheedge sudo[8899]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:56 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:01:56 closetotheedge systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 14 22:01:56 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:01:56 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:56 closetotheedge volumio[8722]: info: ir_controller: LIRC correctly updated. Apr 14 22:01:56 closetotheedge volumio[8722]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Apr 14 22:01:56 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:56 closetotheedge sudo[8904]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:56 closetotheedge sudo[8902]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 22:01:56 closetotheedge volumio[8722]: info: Asound.conf file unchanged, so no further update is needed Apr 14 22:01:56 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:01:56 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:56 closetotheedge sudo[8914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:01:56 closetotheedge sudo[8914]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:56 closetotheedge sudo[8914]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:56 closetotheedge sudo[8916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:01:56 closetotheedge sudo[8916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:57 closetotheedge systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 22:01:57 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:01:57 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:01:57 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:01:57 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:01:57 closetotheedge volumio[8722]: Error adding Membership: Error: addMembership EINVAL Apr 14 22:01:57 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:01:57 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:01:57 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:01:57 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - no preset used Apr 14 22:01:57 closetotheedge sudo[8922]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:01:57 closetotheedge sudo[8922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:57 closetotheedge sudo[8922]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - no preset used Apr 14 22:01:57 closetotheedge volumio[8722]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 14 22:01:57 closetotheedge volumio[8722]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 14 22:01:57 closetotheedge volumio[8722]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 14 22:01:57 closetotheedge volumio[8722]: info: camilladsp service started and running in background, instance 1 Apr 14 22:01:57 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:57 closetotheedge volumio[8722]: cannot open device 'volumioHw': Device or resource busy Apr 14 22:01:57 closetotheedge volumio[8722]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 14 22:01:57 closetotheedge volumio[8722]: cannot open device 'volumioHw': Device or resource busy Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp loaded Apr 14 22:01:57 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:01:57 closetotheedge sudo[8941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 14 22:01:57 closetotheedge sudo[8941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 14 22:01:57 closetotheedge volumio[8722]: info: Adding Signal Path Element [object Object] Apr 14 22:01:57 closetotheedge volumio[8722]: info: Adding fusiondspeq DSP Signal Path Element Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - ---- installed callbackRead Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - Loudness is ON true Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - Nulleq applied Apr 14 22:01:57 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:57 closetotheedge systemd[1]: Started FusionDsp Daemon. Apr 14 22:01:57 closetotheedge sudo[8941]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:57 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:01:57 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:01:57 closetotheedge volumio[8722]: info: FusionDsp - Apr 14 22:01:57 closetotheedge sudo[8945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Apr 14 22:01:57 closetotheedge sudo[8945]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:57 closetotheedge systemd[1]: Stopping Flexible IR remote input/output application support... Apr 14 22:01:57 closetotheedge lircd[1282]: lircd-0.10.1[1282]: Notice: caught signal Apr 14 22:01:57 closetotheedge lircd-0.10.1[1282]: Notice: caught signal Apr 14 22:01:57 closetotheedge systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Apr 14 22:01:57 closetotheedge systemd[1]: lircd.service: Succeeded. Apr 14 22:01:57 closetotheedge systemd[1]: Stopped Flexible IR remote input/output application support. Apr 14 22:01:57 closetotheedge systemd[1]: irexec.service: Succeeded. Apr 14 22:01:57 closetotheedge systemd[1]: Starting lircd(8) initialization helper tool... Apr 14 22:01:58 closetotheedge volumio[8722]: info: FusionDsp - Apr 14 22:01:58 closetotheedge lircd-setup[8951]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Apr 14 22:01:58 closetotheedge lircd-setup[8951]: parser = configparser.SafeConfigParser() Apr 14 22:01:58 closetotheedge systemd[1]: lircd-setup.service: Succeeded. Apr 14 22:01:58 closetotheedge systemd[1]: Started lircd(8) initialization helper tool. Apr 14 22:01:58 closetotheedge systemd[1]: Starting Flexible IR remote input/output application support... Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: lircd: Opening log, level: Info Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Version: lircd 0.10.1 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: System info: Linux closetotheedge 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l GNU/Linux Apr 14 22:01:58 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 14 22:01:58 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:01:58 closetotheedge volumio[8722]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: Initial device: /dev/lirc0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: [lirc] protocol is enabled Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: Initial device: /dev/lirc0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: [lirc] protocol is enabled Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Info: lircd: Opening log, level: Info Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: driver: default Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Notice: Using systemd fd Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Warning: Running as root Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Info: Using remote: lircd.conf. Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: output: /var/run/lirc/lircd Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: nodaemon: 1 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: logfile: syslog Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: immediate-init: 0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: permission: 666 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: driver-options: Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: listen: 0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: connect: (null) Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: userelease: 0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: effective_user: (null) Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: release_suffix: _EVUP Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: allow_simulate: 0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: repeat_max: 600 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Options: dynamic_codes: (null) Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Current driver: default Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Driver API version: 3 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Driver version: 0.10.0 Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: lircd: Opening log, level: Info Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: Using systemd fd Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Warning: Running as root Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: Using remote: lircd.conf. Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 14 22:01:58 closetotheedge systemd[1]: Started Flexible IR remote input/output application support. Apr 14 22:01:58 closetotheedge sudo[8960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:01:58 closetotheedge sudo[8960]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:58 closetotheedge sudo[8945]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:58 closetotheedge volumio[8722]: info: ir_controller: systemctl restart lircd.service succeeded. Apr 14 22:01:58 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:01:58 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:01:58 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:58 closetotheedge sudo[8963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Apr 14 22:01:58 closetotheedge sudo[8963]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:01:58 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:01:58 closetotheedge sudo[8960]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:58 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:01:58 closetotheedge systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Apr 14 22:01:58 closetotheedge sudo[8963]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:58 closetotheedge volumio[8722]: info: ir_controller: systemctl restart irexec.service succeeded. Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Notice: accepted new client on /var/run/lirc/lircd Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Notice: accepted new client on /var/run/lirc/lircd Apr 14 22:01:58 closetotheedge lircd[8955]: lircd-0.10.1[8955]: Info: [lirc] protocol is enabled Apr 14 22:01:58 closetotheedge lircd-0.10.1[8955]: Info: [lirc] protocol is enabled Apr 14 22:01:59 closetotheedge mpd[8924]: Apr 14 22:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 22:01:59 closetotheedge systemd[1]: Started Music Player Daemon. Apr 14 22:01:59 closetotheedge sudo[8916]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:59 closetotheedge sudo[8847]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:59 closetotheedge sudo[8859]: pam_unix(sudo:session): session closed for user root Apr 14 22:01:59 closetotheedge volumio[8722]: info: Completed starting Core Plugins Apr 14 22:01:59 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:59 closetotheedge volumio[8722]: info: ----- MyVolumio plugins startup ---- Apr 14 22:01:59 closetotheedge volumio[8722]: info: ------------------------------------------- Apr 14 22:01:59 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 14 22:01:59 closetotheedge volumio[8722]: error: MPD error: The expression evaluated to a falsy value: Apr 14 22:01:59 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:01:59 closetotheedge volumio[8722]: error: The expression evaluated to a falsy value: Apr 14 22:01:59 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:01:59 closetotheedge volumio[8722]: error: MPD error: The expression evaluated to a falsy value: Apr 14 22:01:59 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:01:59 closetotheedge volumio[8722]: error: The expression evaluated to a falsy value: Apr 14 22:01:59 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:01:59 closetotheedge volumio[8722]: info: MPD running with PID8924 Apr 14 22:01:59 closetotheedge volumio[8722]: ,establishing connection Apr 14 22:01:59 closetotheedge volumio[8722]: error: updateQueue error: null Apr 14 22:01:59 closetotheedge volumio[8722]: error: updateQueue error: null Apr 14 22:02:00 closetotheedge sudo[8988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 22:02:00 closetotheedge sudo[8988]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:00 closetotheedge sudo[8988]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:00 closetotheedge volumio[8722]: info: Cannot mount NAS Public at system boot, trial number 3 ,retrying in 5 seconds Apr 14 22:02:00 closetotheedge sudo[8991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 22:02:00 closetotheedge sudo[8991]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:00 closetotheedge sudo[8991]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:00 closetotheedge sudo[8994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 22:02:00 closetotheedge sudo[8994]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:01 closetotheedge sudo[8994]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:01 closetotheedge volumio[8722]: info: Upmpdcli Daemon Started Apr 14 22:02:02 closetotheedge volumio-remote-updater[626]: [2026-04-14 22:02:02] [connect] Successful connection Apr 14 22:02:02 closetotheedge volumio-remote-updater[626]: [2026-04-14 22:02:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1776214922 101 Apr 14 22:02:02 closetotheedge volumio[8722]: 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: 7 Apr 14 22:02:04 closetotheedge volumio[8722]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 14 22:02:05 closetotheedge volumio[8722]: info: Cannot mount NAS Public at system boot, trial number 4 ,retrying in 5 seconds Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin bluetooth to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin multiroom to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin metavolumio to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin cd_controller to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 14 22:02:08 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 14 22:02:09 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 14 22:02:09 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 14 22:02:09 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:09 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:09 closetotheedge volumio[8722]: info: Starting MyVolumio Remote Streaming Endpoints Apr 14 22:02:09 closetotheedge volumio[8722]: info: MyVolumio login type: Token Apr 14 22:02:09 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 14 22:02:09 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 14 22:02:10 closetotheedge volumio[8722]: info: Starting Streaming Service Transparent Proxy Apr 14 22:02:10 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 14 22:02:10 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 14 22:02:10 closetotheedge volumio[8722]: info: Streaming services startup Apr 14 22:02:10 closetotheedge volumio[8722]: info: Starting Streaming Daemon Apr 14 22:02:11 closetotheedge sudo[9017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 22:02:11 closetotheedge sudo[9017]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:11 closetotheedge sudo[9017]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:11 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 14 22:02:11 closetotheedge volumio[8722]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 14 22:02:11 closetotheedge volumio[8722]: error: Cannot start Volumio Streaming Daemon Apr 14 22:02:11 closetotheedge volumio[8722]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 22:02:11 closetotheedge volumio[8722]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 22:02:11 closetotheedge volumio[8722]: error: MyVolumio Custom Token format not valid, refreshing it Apr 14 22:02:12 closetotheedge volumio[8722]: STREAMING PROXY: Starting server on port 3245 Apr 14 22:02:12 closetotheedge volumio[8722]: Node JS runtime: 14 Apr 14 22:02:12 closetotheedge volumio[8722]: info: MyVolumio login type: Token Apr 14 22:02:13 closetotheedge volumio[8722]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 14 22:02:15 closetotheedge volumio[8722]: info: MyVolumio token set successfully Apr 14 22:02:15 closetotheedge volumio[8722]: info: MYVOLUMIO: Adding device Apr 14 22:02:15 closetotheedge volumio[8722]: info: MYVOLUMIO: Evaluating Server Apr 14 22:02:16 closetotheedge volumio[8722]: info: MyVolumio Plan changed: premium Apr 14 22:02:16 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 14 22:02:16 closetotheedge volumio[8722]: info: Removing browser output: myVolumio user plan is not superstar Apr 14 22:02:16 closetotheedge volumio[8722]: info: Removing audio output: Apr 14 22:02:16 closetotheedge volumio[8722]: info: MYVOLUMIO: Adding device Apr 14 22:02:16 closetotheedge volumio[8722]: info: MYVOLUMIO: Evaluating Server Apr 14 22:02:16 closetotheedge volumio[8722]: info: Remote config written successfully Apr 14 22:02:16 closetotheedge volumio[8722]: info: Starting Tunnel 1 Apr 14 22:02:16 closetotheedge volumio[8722]: info: Starting Tunnel Connection Checker Apr 14 22:02:16 closetotheedge volumio[8722]: info: Completed starting MyVolumio Plugin Apr 14 22:02:17 closetotheedge volumio[8722]: info: MYVolumio Device enabled Apr 14 22:02:17 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 14 22:02:17 closetotheedge volumio[8722]: info: MyVolumio status changed Apr 14 22:02:17 closetotheedge volumio[8722]: info: Streaming services startup Apr 14 22:02:17 closetotheedge volumio[8722]: info: Starting Streaming Daemon Apr 14 22:02:17 closetotheedge sudo[9064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 22:02:17 closetotheedge sudo[9064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:17 closetotheedge sudo[9064]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:17 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 14 22:02:17 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 14 22:02:17 closetotheedge volumio[8722]: info: [1776214937350] Starting BluetoothController Apr 14 22:02:17 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 14 22:02:19 closetotheedge sudo[9067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 14 22:02:19 closetotheedge sudo[9067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:19 closetotheedge sudo[9067]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:19 closetotheedge volumio[8722]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: MultiRoom plugin initialized Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: STOPPING SNAPCLIENT Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: Snap server stop Apr 14 22:02:19 closetotheedge sudo[9083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 14 22:02:19 closetotheedge sudo[9083]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: STOPPING volumioStreaming Apr 14 22:02:19 closetotheedge sudo[9086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 14 22:02:19 closetotheedge sudo[9086]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:19 closetotheedge sudo[9083]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:19 closetotheedge sudo[9089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 14 22:02:19 closetotheedge sudo[9089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 14 22:02:19 closetotheedge sudo[9092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 14 22:02:19 closetotheedge sudo[9092]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:19 closetotheedge sudo[9086]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:19 closetotheedge sudo[9092]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:19 closetotheedge sudo[9089]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 14 22:02:19 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:02:19 closetotheedge volumio[8722]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 22:02:19 closetotheedge volumio[8722]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 14 22:02:19 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:02:19 closetotheedge volumio[8722]: info: Setting Geolocation for MyVolumio to us1 Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: Setting Geolocation for MyVolumio to us1 Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: Removed streaming files Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: volumioStreaming STOPPED Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: SNAPSERVER STOPPED Apr 14 22:02:19 closetotheedge volumio[8722]: info: MRS: SNAPCLIENT STOPPED Apr 14 22:02:19 closetotheedge volumio[8722]: error: Cannot start Volumio Streaming Daemon Apr 14 22:02:19 closetotheedge volumio[8722]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 22:02:19 closetotheedge volumio[8722]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 22:02:20 closetotheedge volumio[8722]: info: Asound.conf file written Apr 14 22:02:20 closetotheedge sudo[9096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 22:02:20 closetotheedge sudo[9096]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge sudo[9096]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Apr 14 22:02:20 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Apr 14 22:02:20 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Apr 14 22:02:20 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:02:20 closetotheedge sudo[9101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:02:20 closetotheedge sudo[9101]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:20 closetotheedge sudo[9101]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge sudo[9104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:02:20 closetotheedge sudo[9104]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge systemd[1]: Stopping Music Player Daemon... Apr 14 22:02:20 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:02:20 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:20 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:02:20 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 22:02:20 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 14 22:02:20 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output for this device Apr 14 22:02:20 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:20 closetotheedge sudo[9113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name CloseToTheEdge Apr 14 22:02:20 closetotheedge sudo[9113]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding audio output: Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding audio output: Apr 14 22:02:20 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:20 closetotheedge sudo[9113]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding METAVOLUMIO REST API Endpoints Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 14 22:02:20 closetotheedge volumio[8722]: info: Preparing CD Folders Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding CD REST API Endpoints Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 14 22:02:20 closetotheedge volumio[8722]: info: Starting UDEV Watcher for CD Apr 14 22:02:20 closetotheedge volumio[8722]: info: Detecting CD presence with UDEV Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding inputs REST Endpoints Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 14 22:02:20 closetotheedge volumio[8722]: info: Scanning Audio Inputs Apr 14 22:02:20 closetotheedge sudo[9110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:02:20 closetotheedge sudo[9110]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge sudo[9110]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge volumio[8722]: info: Checking against Known Cards name Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding Server instance for streaming Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 14 22:02:20 closetotheedge volumio[8722]: error: Hi Res Audio Failed Login: Missing Login Data Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 14 22:02:20 closetotheedge volumio[8722]: info: Refreshing TIDAL token Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 14 22:02:20 closetotheedge volumio[8722]: info: Refreshing QOBUZ token Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 14 22:02:20 closetotheedge volumio[8722]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Apr 14 22:02:20 closetotheedge volumio[8722]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 14 22:02:20 closetotheedge sudo[9123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 22:02:20 closetotheedge sudo[9123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge volumio[8722]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 14 22:02:20 closetotheedge systemd[1]: Stopping Volumio Tidal Connect Service... Apr 14 22:02:20 closetotheedge systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:20 closetotheedge systemd[1]: vtcs.service: Succeeded. Apr 14 22:02:20 closetotheedge systemd[1]: Stopped Volumio Tidal Connect Service. Apr 14 22:02:20 closetotheedge sudo[9123]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge sudo[9130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 22:02:20 closetotheedge sudo[9130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge volumio[8722]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 14 22:02:20 closetotheedge sudo[9130]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge sudo[9132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 22:02:20 closetotheedge sudo[9132]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge volumio[8722]: info: FusionDsp - Stopping FusionDsp service Apr 14 22:02:20 closetotheedge volumio[8722]: info: camilladsp stopping service pid 8931... Apr 14 22:02:20 closetotheedge systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 22:02:20 closetotheedge qobuz-connect[1504]: 20260414 22:02:20.701 [1504.1504] INFO SampleApp: Stopping Local configuration server Apr 14 22:02:20 closetotheedge systemd[1]: qobuz-connect.service: Succeeded. Apr 14 22:02:20 closetotheedge systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 22:02:20 closetotheedge volumio[8722]: info: camilladsp service terminated, instance 1 Apr 14 22:02:20 closetotheedge volumio[8722]: info: Disabling plugin fusiondsp Apr 14 22:02:20 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:02:20 closetotheedge systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 22:02:20 closetotheedge sudo[9132]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:20 closetotheedge sudo[9151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 14 22:02:20 closetotheedge sudo[9151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:20 closetotheedge systemd[1]: Stopping FusionDsp Daemon... Apr 14 22:02:20 closetotheedge volumio[8943]: Backend configuration: Apr 14 22:02:20 closetotheedge volumio[8943]: {active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml, active_config_txt: null, Apr 14 22:02:20 closetotheedge volumio[8943]: camilla_host: 127.0.0.1, camilla_port: 9876, coeff_dir: /data/INTERNAL/FusionDsp/filters, Apr 14 22:02:20 closetotheedge volumio[8943]: config_dir: /data/configuration/audio_interface/fusiondsp, default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml, Apr 14 22:02:20 closetotheedge volumio[8943]: log_file: /tmp/camilladsp.log, on_get_active_config: null, on_set_active_config: null, Apr 14 22:02:20 closetotheedge volumio[8943]: port: 5011, supported_capture_types: null, supported_playback_types: null, update_config_symlink: false, Apr 14 22:02:20 closetotheedge volumio[8943]: update_config_txt: false} Apr 14 22:02:20 closetotheedge volumio[8943]: ======== Running on http://0.0.0.0:5011 ======== Apr 14 22:02:20 closetotheedge volumio[8943]: (Press CTRL+C to quit) Apr 14 22:02:20 closetotheedge volumio[8722]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 14 22:02:20 closetotheedge volumio[8722]: info: Bluetooth name changed to CloseToTheEdge Apr 14 22:02:20 closetotheedge volumio[8722]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 14 22:02:20 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:02:20 closetotheedge volumio[8722]: info: Executing endpoint qc_getconfig Apr 14 22:02:20 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.038 [9142.9142] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 22:02:21 closetotheedge volumio[8722]: info: Updating MyVolumio device info Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.049 [9142.9142] INFO VolumeManager: [0x52a360]: Setting new playback volume: 75 Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.049 [9142.9142] INFO VolumeManager: [0x52a360]: Setting new mute state: 0 Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.049 [9142.9142] INFO AudioStreamManager: [0x52a0b8]: Setting new audio download buffer size: 1048576 Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.049 [9142.9142] INFO QobuzConnect: [0x52ac28]: Client initialized! Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.049 [9142.9142] INFO SampleApp: Starting Avahi advertising, name: CloseToTheEdge, service name: _qobuz-connect._tcp Apr 14 22:02:21 closetotheedge systemd[1]: fusiondsp.service: Succeeded. Apr 14 22:02:21 closetotheedge systemd[1]: Stopped FusionDsp Daemon. Apr 14 22:02:21 closetotheedge volumio[8722]: info: Updating MyVolumio device info Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge sudo[9151]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.087 [9142.9142] INFO LocalConfigManager: [0x529de0]: Starting Local Configuration server Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.087 [9142.9142] INFO SampleApp: Starting Local configuration server Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.087 [9142.9142] INFO SampleApp: Connected to UNIX socket client 0x51ff60 Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: Successfully Added MyVolumio device Apr 14 22:02:21 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 22:02:21 closetotheedge volumio[8722]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 22:02:21 closetotheedge volumio[8722]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 14 22:02:21 closetotheedge volumio[8722]: info: Removing fusiondspeq DSP Signal Path Element Apr 14 22:02:21 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:02:21 closetotheedge volumio[8722]: info: Asound.conf file written Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.189 [9142.9142] INFO SampleApp: Playback volume changed: 75 Apr 14 22:02:21 closetotheedge sudo[9163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:02:21 closetotheedge sudo[9163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge sudo[9165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 22:02:21 closetotheedge sudo[9165]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:02:21 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:02:21 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:21 closetotheedge sudo[9165]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:21 closetotheedge sudo[9163]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Apr 14 22:02:21 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Apr 14 22:02:21 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Apr 14 22:02:21 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:02:21 closetotheedge sudo[9173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:02:21 closetotheedge sudo[9173]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge sudo[9173]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge sudo[9177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:02:21 closetotheedge sudo[9177]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:21 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:02:21 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:02:21 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:02:21 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:21 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:21 closetotheedge volumio[8722]: info: Done. Apr 14 22:02:21 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:21 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:21 closetotheedge sudo[9189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 22:02:21 closetotheedge volumio[8722]: info: Successfully Added MyVolumio device Apr 14 22:02:21 closetotheedge sudo[9189]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge sudo[9189]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge sudo[9191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 22:02:21 closetotheedge sudo[9191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.632 [9142.9142] INFO SampleApp: Stopping Local configuration server Apr 14 22:02:21 closetotheedge qobuz-connect[9142]: 20260414 22:02:21.642 [9142.9142] INFO SampleApp: shat down connection on UNIX socket Apr 14 22:02:21 closetotheedge systemd[1]: qobuz-connect.service: Succeeded. Apr 14 22:02:21 closetotheedge systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 22:02:21 closetotheedge sudo[9182]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:02:21 closetotheedge sudo[9182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:21 closetotheedge sudo[9182]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 22:02:21 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:02:21 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:02:21 closetotheedge sudo[9191]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:21 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 22:02:21 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:21 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:21 closetotheedge volumio[8722]: info: Executing endpoint qc_getconfig Apr 14 22:02:21 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.006 [9193.9193] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.013 [9193.9193] INFO VolumeManager: [0x7e1360]: Setting new playback volume: 75 Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.013 [9193.9193] INFO VolumeManager: [0x7e1360]: Setting new mute state: 0 Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.013 [9193.9193] INFO AudioStreamManager: [0x7e10b8]: Setting new audio download buffer size: 1048576 Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.013 [9193.9193] INFO QobuzConnect: [0x7e1c28]: Client initialized! Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.013 [9193.9193] INFO SampleApp: Starting Avahi advertising, name: CloseToTheEdge, service name: _qobuz-connect._tcp Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.030 [9193.9193] INFO LocalConfigManager: [0x7e0de0]: Starting Local Configuration server Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.031 [9193.9193] INFO SampleApp: Starting Local configuration server Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.031 [9193.9193] INFO SampleApp: Connected to UNIX socket client 0x7d6f60 Apr 14 22:02:22 closetotheedge volumio[8722]: info: Access Token successfully retrieved Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:02:22 closetotheedge volumio[8722]: info: [1776214942106] CoreMusicLibrary::Adding element QOBUZ Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:02:22 closetotheedge volumio[8722]: Cannot find translation for source QOBUZ Apr 14 22:02:22 closetotheedge volumio[8722]: info: Stopping AccessToken refresher cron for QOBUZ Apr 14 22:02:22 closetotheedge qobuz-connect[9193]: 20260414 22:02:22.156 [9193.9193] INFO SampleApp: Playback volume changed: 75 Apr 14 22:02:22 closetotheedge volumio[8722]: info: AccessToken refresher cron started for QOBUZ Apr 14 22:02:22 closetotheedge volumio[8722]: info: Adding QOBUZ REST API Endpoints Apr 14 22:02:22 closetotheedge volumio[8722]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 14 22:02:22 closetotheedge volumio[8722]: info: Access Token successfully retrieved Apr 14 22:02:22 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 22:02:22 closetotheedge volumio[8722]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 22:02:22 closetotheedge volumio[8722]: info: Successfully Updated MyVolumio device Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:22 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:22 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:02:22 closetotheedge volumio[8722]: info: Successfully Updated MyVolumio device Apr 14 22:02:22 closetotheedge sudo[9209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:02:22 closetotheedge sudo[9209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:22 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:02:22 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:02:22 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:02:22 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:22 closetotheedge volumio[8722]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 22:02:22 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:02:22 closetotheedge volumio[8722]: info: Asound.conf file written Apr 14 22:02:22 closetotheedge sudo[9213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 14 22:02:22 closetotheedge sudo[9213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:22 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:22 closetotheedge sudo[9216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 22:02:22 closetotheedge sudo[9209]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:22 closetotheedge sudo[9216]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:22 closetotheedge sudo[9216]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:22 closetotheedge autossh[1521]: received signal to exit (15) Apr 14 22:02:22 closetotheedge systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 14 22:02:22 closetotheedge systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:22 closetotheedge systemd[1]: sshtunnel.service: Succeeded. Apr 14 22:02:22 closetotheedge systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 14 22:02:22 closetotheedge systemd[1]: Started MyVolumio SSH Tunnel. Apr 14 22:02:22 closetotheedge sudo[9213]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:22 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Apr 14 22:02:22 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Apr 14 22:02:22 closetotheedge volumio[8722]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Apr 14 22:02:22 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:02:22 closetotheedge autossh[9222]: port set to 0, monitoring disabled Apr 14 22:02:22 closetotheedge autossh[9222]: starting ssh (count 1) Apr 14 22:02:22 closetotheedge autossh[9222]: ssh child pid is 9229 Apr 14 22:02:22 closetotheedge sudo[9225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:02:22 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:22 closetotheedge sudo[9225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:22 closetotheedge sudo[9225]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:22 closetotheedge sudo[9230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:02:22 closetotheedge sudo[9230]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:22 closetotheedge systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:22 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:02:22 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:02:22 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:02:22 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:22 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:22 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:22 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:23 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:02:23 closetotheedge volumio[8722]: info: Successfully retrieved User Session From TIDAL Apr 14 22:02:23 closetotheedge sudo[9243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 22:02:23 closetotheedge sudo[9243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:23 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:02:23 closetotheedge volumio[8722]: info: Remote SSH Started Apr 14 22:02:23 closetotheedge sudo[9243]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:23 closetotheedge sudo[9245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 22:02:23 closetotheedge sudo[9245]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:23 closetotheedge qobuz-connect[9193]: 20260414 22:02:23.142 [9193.9193] INFO SampleApp: Stopping Local configuration server Apr 14 22:02:23 closetotheedge systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 22:02:23 closetotheedge qobuz-connect[9193]: 20260414 22:02:23.153 [9193.9193] INFO SampleApp: shat down connection on UNIX socket Apr 14 22:02:23 closetotheedge systemd[1]: qobuz-connect.service: Succeeded. Apr 14 22:02:23 closetotheedge systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 22:02:23 closetotheedge sudo[9236]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:02:23 closetotheedge sudo[9236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:23 closetotheedge sudo[9236]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:23 closetotheedge systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 22:02:23 closetotheedge sudo[9245]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:23 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:02:23 closetotheedge sudo[9253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 14 22:02:23 closetotheedge sudo[9253]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:23 closetotheedge sudo[9253]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:23 closetotheedge volumiossh-tunnel[9219]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Apr 14 22:02:23 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 22:02:23 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:23 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:23 closetotheedge volumio[8722]: info: Executing endpoint qc_getconfig Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.568 [9247.9247] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 22:02:23 closetotheedge volumio[8722]: info: Volumio BT Module successfully started Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.579 [9247.9247] INFO VolumeManager: [0x2162360]: Setting new playback volume: 75 Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.579 [9247.9247] INFO VolumeManager: [0x2162360]: Setting new mute state: 0 Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.579 [9247.9247] INFO AudioStreamManager: [0x21620b8]: Setting new audio download buffer size: 1048576 Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.579 [9247.9247] INFO QobuzConnect: [0x2162c28]: Client initialized! Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.579 [9247.9247] INFO SampleApp: Starting Avahi advertising, name: CloseToTheEdge, service name: _qobuz-connect._tcp Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.596 [9247.9247] INFO LocalConfigManager: [0x2161de0]: Starting Local Configuration server Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.596 [9247.9247] INFO SampleApp: Starting Local configuration server Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.597 [9247.9247] INFO SampleApp: Connected to UNIX socket client 0x2157f60 Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:23 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 14 22:02:23 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 22:02:23 closetotheedge volumio[8722]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 22:02:23 closetotheedge volumio[8722]: info: TidalConnect service stoped! Apr 14 22:02:23 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:02:23 closetotheedge qobuz-connect[9247]: 20260414 22:02:23.718 [9247.9247] INFO SampleApp: Playback volume changed: 75 Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:23 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:23 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:23 closetotheedge sudo[9267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:02:23 closetotheedge sudo[9267]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:23 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:02:23 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:02:23 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:23 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:23 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:23 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:23 closetotheedge volumio[8722]: info: Successfully retrieved User Subscription From TIDAL Apr 14 22:02:23 closetotheedge volumio[8722]: info: Adding TIDAL to Browse Sources Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 14 22:02:23 closetotheedge volumio[8722]: info: [1776214943885] CoreMusicLibrary::Adding element TIDAL Apr 14 22:02:23 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 22:02:23 closetotheedge volumio[8722]: Cannot find translation for source QOBUZ Apr 14 22:02:23 closetotheedge volumio[8722]: Cannot find translation for source TIDAL Apr 14 22:02:23 closetotheedge sudo[9267]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:23 closetotheedge volumio[8722]: info: Adding TIDAL REST API Endpoints Apr 14 22:02:23 closetotheedge volumio[8722]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 14 22:02:23 closetotheedge volumio[8722]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 22:02:23 closetotheedge volumio[8722]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 22:02:23 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:02:24 closetotheedge sudo[9277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 22:02:24 closetotheedge sudo[9277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:24 closetotheedge systemd[1]: Started Volumio Tidal Connect Service. Apr 14 22:02:24 closetotheedge sudo[9277]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:24 closetotheedge volumio[8722]: info: Executing endpoint tc_getconfig Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 22:02:24 closetotheedge vtcs[9279]: STARTING TidalConnect services, version: 1.6.1 Apr 14 22:02:24 closetotheedge vtcs[9279]: STARTED TidalConnect services. Apr 14 22:02:24 closetotheedge volumio[8722]: info: Executing endpoint tc_connect Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 14 22:02:24 closetotheedge volumio[8722]: info: Connecting to TidalConnect Apr 14 22:02:24 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::servicePushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 14 22:02:24 closetotheedge volumio[8722]: verbose: CURRENT POSITION 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::syncState stateService stop Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::syncState currentStatus stop Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: No code Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::servicePushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 14 22:02:24 closetotheedge volumio[8722]: verbose: CURRENT POSITION 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::syncState stateService stop Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::syncState currentStatus stop Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: No code Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreStateMachine::pushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioPushState Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output update for this device Apr 14 22:02:24 closetotheedge volumio[8722]: info: MRS: Pushing multiroomSync output Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:24 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:24 closetotheedge mpd[9250]: Apr 14 22:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 22:02:24 closetotheedge systemd[1]: Started Music Player Daemon. Apr 14 22:02:24 closetotheedge sudo[9104]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:24 closetotheedge sudo[9230]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:24 closetotheedge sudo[9177]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:24 closetotheedge volumio[8722]: error: MPD error: The expression evaluated to a falsy value: Apr 14 22:02:24 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:02:24 closetotheedge volumio[8722]: error: The expression evaluated to a falsy value: Apr 14 22:02:24 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:02:24 closetotheedge volumio[8722]: error: MPD error: The expression evaluated to a falsy value: Apr 14 22:02:24 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:02:24 closetotheedge volumio[8722]: error: The expression evaluated to a falsy value: Apr 14 22:02:24 closetotheedge volumio[8722]: assert.ok(self.idling) Apr 14 22:02:24 closetotheedge volumio[8722]: error: updateQueue error: null Apr 14 22:02:25 closetotheedge volumio[8722]: info: MRS: Getting audio outputs on start Apr 14 22:02:25 closetotheedge volumio[8722]: info: MRS: Requesting all other devices output Apr 14 22:02:25 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:02:25 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:25 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 22:02:25 closetotheedge volumio-remote-updater[626]: No test mode Apr 14 22:02:25 closetotheedge volumio-remote-updater[626]: No alpha test mode Apr 14 22:02:25 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 14 22:02:26 closetotheedge volumio[8722]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 14 22:02:26 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:26.364-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:26 closetotheedge volumio[8722]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 22:02:26 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 22:02:27 closetotheedge volumio[8722]: info: TidalConnect service started! Apr 14 22:02:27 closetotheedge volumio[8722]: [Metrics] CommandRouter: 39s 166.89ms Apr 14 22:02:27 closetotheedge volumio[8722]: info: CoreCommandRouter::volumiosetStartupVolume Apr 14 22:02:27 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:27 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:27 closetotheedge volumio[8722]: info: CoreCommandRouter::Close All Modals sent Apr 14 22:02:27 closetotheedge volumio[8722]: info: CoreCommandRouter::Close All Modals sent Apr 14 22:02:28 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 14 22:02:28 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 22:02:28 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:02:29 closetotheedge volumio[8722]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 14 22:02:29 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:29.421-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:29 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:29.430-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:29 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:29.900-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:29 closetotheedge volumio[8722]: info: Check plugin dependencies Apr 14 22:02:29 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 22:02:29 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:29.978-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:29 closetotheedge volumio[8722]: info: Rename folder Apr 14 22:02:30 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:30.016-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:30 closetotheedge volumio[8722]: info: Move to category Apr 14 22:02:30 closetotheedge volumio[8722]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 22:02:36 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:36.715-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:36 closetotheedge volumio[8722]: info: Checking if install.sh is present Apr 14 22:02:36 closetotheedge volumio[8722]: info: BOOT COMPLETED Apr 14 22:02:36 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 22:02:36 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 22:02:36 closetotheedge volumio[8722]: info: Executing install.sh Apr 14 22:02:36 closetotheedge sudo[9383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 14 22:02:36 closetotheedge sudo[9383]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:36 closetotheedge volumio[8722]: info: creating filters folder and copying demo filters Apr 14 22:02:36 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:36.885-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:36 closetotheedge volumio[8722]: info: copying demo flters Apr 14 22:02:36 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:36.932-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/ Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.026-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/ Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/PEQ/ Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/convfir/ Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/convfir/kef.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/convfir/test.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/ Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio[8722]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.061-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.062-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.063-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.064-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.066-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.067-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.068-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.069-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.071-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge sudo[9409]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.072-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.074-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge sudo[9409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.075-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.081-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.082-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.083-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.084-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio[8722]: info: Installing/fusiondsp dependencies Apr 14 22:02:37 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:37.224-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:37 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 22:02:38 closetotheedge volumio[8722]: info: Hit:1 https://apt2.volumio.org/raspbian buster InRelease Apr 14 22:02:38 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:38.851-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:38 closetotheedge volumio[8722]: info: Hit:2 https://archive2.volumio.org/debian buster InRelease Apr 14 22:02:38 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:38.868-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:44 closetotheedge volumio[8722]: info: Reading package lists... Apr 14 22:02:44 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:44.322-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:45 closetotheedge volumio[8722]: info: Building dependency tree... Apr 14 22:02:45 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:45.284-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:46 closetotheedge volumio[8722]: info: Enabling plugin fusiondsp Apr 14 22:02:46 closetotheedge volumio[8722]: info: Loading plugin "fusiondsp"... Apr 14 22:02:46 closetotheedge volumio[8722]: info: Applying required configuration parameters for plugin fusiondsp Apr 14 22:02:48 closetotheedge sudo[9409]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:48 closetotheedge sudo[9554]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 14 22:02:48 closetotheedge sudo[9554]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:53 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:02:53 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:53 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:53 closetotheedge volumio[8722]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 22:02:53 closetotheedge volumio[8722]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 14 22:02:53 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:02:53 closetotheedge volumio[8722]: info: Reading state information... Apr 14 22:02:53 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:53.852-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:53 closetotheedge volumio[8722]: info: All packages are up to date. Apr 14 22:02:53 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:53.870-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:54 closetotheedge volumio[8722]: info: Reading package lists... Apr 14 22:02:54 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:54.006-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:54 closetotheedge volumio[8722]: info: Asound.conf file written Apr 14 22:02:54 closetotheedge sudo[9574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 22:02:54 closetotheedge sudo[9574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge sudo[9574]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge volumio[8722]: xcb_connection_has_error() returned true Apr 14 22:02:54 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:02:54 closetotheedge sudo[9579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:02:54 closetotheedge sudo[9579]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:02:54 closetotheedge sudo[9579]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:54 closetotheedge sudo[9582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:02:54 closetotheedge sudo[9582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge systemd[1]: Stopping Music Player Daemon... Apr 14 22:02:54 closetotheedge volumio[8722]: xcb_connection_has_error() returned true Apr 14 22:02:54 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:02:54 closetotheedge volumio[8722]: xcb_connection_has_error() returned true Apr 14 22:02:54 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:02:54 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:02:54 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:54 closetotheedge vtcs[9279]: [2026-04-14 22:02:54.286] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 14 22:02:54 closetotheedge vtcs[9279]: [2026-04-14 22:02:54.287] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Apr 14 22:02:54 closetotheedge vtcs[9279]: [2026-04-14 22:02:54.287] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 14 22:02:54 closetotheedge sudo[9591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 22:02:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:54 closetotheedge volumio[8722]: info: PLUGIN START: fusiondsp Apr 14 22:02:54 closetotheedge volumio[8722]: info: Loading i18n strings for locale en Apr 14 22:02:54 closetotheedge sudo[9591]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: FusionDsp - mixtype--------------------- Hardware Apr 14 22:02:54 closetotheedge systemd[1]: Stopping Volumio Tidal Connect Service... Apr 14 22:02:54 closetotheedge volumio[8722]: info: Preparing to generate the ALSA configuration file Apr 14 22:02:54 closetotheedge systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:54 closetotheedge systemd[1]: vtcs.service: Succeeded. Apr 14 22:02:54 closetotheedge systemd[1]: Stopped Volumio Tidal Connect Service. Apr 14 22:02:54 closetotheedge sudo[9591]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge volumio[8722]: info: Done. Apr 14 22:02:54 closetotheedge sudo[9588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:02:54 closetotheedge sudo[9596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 22:02:54 closetotheedge sudo[9588]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge sudo[9588]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge sudo[9596]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge sudo[9604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 22:02:54 closetotheedge volumio[8722]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 14 22:02:54 closetotheedge volumio[8722]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 14 22:02:54 closetotheedge volumio[8722]: info: Reading ALSA contributions from plugins. Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:54 closetotheedge sudo[9604]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge sudo[9604]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:54 closetotheedge sudo[9606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 22:02:54 closetotheedge sudo[9606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:54 closetotheedge sudo[9596]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 22:02:54 closetotheedge qobuz-connect[9247]: 20260414 22:02:54.660 [9247.9247] INFO SampleApp: Stopping Local configuration server Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:54 closetotheedge qobuz-connect[9247]: 20260414 22:02:54.670 [9247.9247] INFO SampleApp: shat down connection on UNIX socket Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:54 closetotheedge systemd[1]: qobuz-connect.service: Succeeded. Apr 14 22:02:54 closetotheedge systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 22:02:54 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:02:54 closetotheedge systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 22:02:54 closetotheedge sudo[9606]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:54 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:54 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 22:02:54 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:54 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:54 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:55 closetotheedge volumio[8722]: info: Executing endpoint qc_getconfig Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.185 [9609.9609] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.191 [9609.9609] INFO VolumeManager: [0x19c9360]: Setting new playback volume: 75 Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.192 [9609.9609] INFO VolumeManager: [0x19c9360]: Setting new mute state: 0 Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.194 [9609.9609] INFO AudioStreamManager: [0x19c90b8]: Setting new audio download buffer size: 1048576 Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.194 [9609.9609] INFO QobuzConnect: [0x19c9c28]: Client initialized! Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.194 [9609.9609] INFO SampleApp: Starting Avahi advertising, name: CloseToTheEdge, service name: _qobuz-connect._tcp Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.217 [9609.9609] INFO LocalConfigManager: [0x19c8de0]: Starting Local Configuration server Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.217 [9609.9609] INFO SampleApp: Starting Local configuration server Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.218 [9609.9609] INFO SampleApp: Connected to UNIX socket client 0x19bef60 Apr 14 22:02:55 closetotheedge volumio[8722]: info: FusionDsp - no preset used Apr 14 22:02:55 closetotheedge qobuz-connect[9609]: 20260414 22:02:55.336 [9609.9609] INFO SampleApp: Playback volume changed: 75 Apr 14 22:02:55 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 22:02:55 closetotheedge volumio[8722]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 22:02:55 closetotheedge volumio[8722]: info: FusionDsp - Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:55 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:55 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:02:55 closetotheedge volumio[8722]: info: Building dependency tree... Apr 14 22:02:55 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:55.591-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:55 closetotheedge volumio[8722]: info: Reading state information... Apr 14 22:02:55 closetotheedge volumio5-onboarding[1135]: time=2026-04-14T22:02:55.603-03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 22:02:55 closetotheedge sudo[9619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:02:55 closetotheedge sudo[9619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:55 closetotheedge volumio[8722]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 14 22:02:55 closetotheedge volumio[8722]: info: Asound.conf file unchanged, so no further update is needed Apr 14 22:02:55 closetotheedge volumio[8722]: info: Output device has changed, restarting MPD Apr 14 22:02:55 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:02:55 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:02:55 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:55 closetotheedge volumio[8722]: info: Output device has changed, restarting Shairport Sync Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:55 closetotheedge sudo[9622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 22:02:55 closetotheedge sudo[9622]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:55 closetotheedge sudo[9622]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:55 closetotheedge sudo[9625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 22:02:55 closetotheedge sudo[9625]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:55 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:55 closetotheedge sudo[9619]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:55 closetotheedge volumio[8722]: xcb_connection_has_error() returned true Apr 14 22:02:55 closetotheedge volumio[8722]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 22:02:55 closetotheedge volumio[8722]: xcb_connection_has_error() returned true Apr 14 22:02:55 closetotheedge volumio[8722]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:55 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:55 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:55 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:55 closetotheedge systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 22:02:55 closetotheedge systemd[1]: mpd.service: Succeeded. Apr 14 22:02:55 closetotheedge systemd[1]: Stopped Music Player Daemon. Apr 14 22:02:55 closetotheedge systemd[1]: Starting Music Player Daemon... Apr 14 22:02:55 closetotheedge sudo[9637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 22:02:55 closetotheedge sudo[9637]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:55 closetotheedge sudo[9640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 22:02:55 closetotheedge sudo[9637]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:55 closetotheedge sudo[9640]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:55 closetotheedge sudo[9640]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:56 closetotheedge sudo[9648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 22:02:56 closetotheedge sudo[9636]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 22:02:56 closetotheedge sudo[9648]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:56 closetotheedge sudo[9636]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:56 closetotheedge sudo[9636]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:56 closetotheedge volumio[8722]: info: MPD Permissions set Apr 14 22:02:56 closetotheedge volumio[8722]: info: Shairport-Sync Started Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge sudo[9648]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge sudo[9653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 22:02:56 closetotheedge volumio[8722]: info: Starting Shairport Sync Apr 14 22:02:56 closetotheedge sudo[9653]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:56 closetotheedge systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 22:02:56 closetotheedge qobuz-connect[9609]: 20260414 22:02:56.219 [9609.9609] INFO SampleApp: Stopping Local configuration server Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 22:02:56 closetotheedge sudo[9659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 22:02:56 closetotheedge qobuz-connect[9609]: 20260414 22:02:56.232 [9609.9609] INFO SampleApp: shat down connection on UNIX socket Apr 14 22:02:56 closetotheedge sudo[9659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:56 closetotheedge volumio[8722]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 22:02:56 closetotheedge volumio[8722]: info: QobuzConnect: setDeactiveState invoked Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::volumioGetState Apr 14 22:02:56 closetotheedge volumio[8722]: info: CorePlayQueue::getTrack 0 Apr 14 22:02:56 closetotheedge volumio[8722]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 22:02:56 closetotheedge systemd[1]: qobuz-connect.service: Succeeded. Apr 14 22:02:56 closetotheedge systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 22:02:56 closetotheedge sudo[9653]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 22:02:56 closetotheedge volumio[8722]: info: Executing endpoint qc_getconfig Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 22:02:56 closetotheedge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 22:02:56 closetotheedge systemd[1]: shairport-sync.service: Succeeded. Apr 14 22:02:56 closetotheedge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:56 closetotheedge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 22:02:56 closetotheedge sudo[9659]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.423 [9660.9660] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.430 [9660.9660] INFO VolumeManager: [0xb58360]: Setting new playback volume: 75 Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.430 [9660.9660] INFO VolumeManager: [0xb58360]: Setting new mute state: 0 Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.430 [9660.9660] INFO AudioStreamManager: [0xb580b8]: Setting new audio download buffer size: 1048576 Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.430 [9660.9660] INFO QobuzConnect: [0xb58c28]: Client initialized! Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.430 [9660.9660] INFO SampleApp: Starting Avahi advertising, name: CloseToTheEdge, service name: _qobuz-connect._tcp Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.458 [9660.9660] INFO LocalConfigManager: [0xb57de0]: Starting Local Configuration server Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.458 [9660.9660] INFO SampleApp: Starting Local configuration server Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.458 [9660.9660] INFO SampleApp: Connected to UNIX socket client 0xb4df60 Apr 14 22:02:56 closetotheedge volumio[8722]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 14 22:02:56 closetotheedge volumio[8722]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 14 22:02:56 closetotheedge volumio[8722]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 14 22:02:56 closetotheedge volumio[8722]: info: camilladsp service started and running in background, instance 2 Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 22:02:56 closetotheedge volumio[8722]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 14 22:02:56 closetotheedge volumio[8722]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 14 22:02:56 closetotheedge volumio[8722]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 14 22:02:56 closetotheedge volumio[8722]: info: FusionDsp loaded Apr 14 22:02:56 closetotheedge volumio[8722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 22:02:56 closetotheedge qobuz-connect[9660]: 20260414 22:02:56.582 [9660.9660] INFO SampleApp: Playback volume changed: 75 Apr 14 22:02:56 closetotheedge sudo[9679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 14 22:02:56 closetotheedge sudo[9679]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:56 closetotheedge volumio[8722]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 14 22:02:56 closetotheedge volumio[8722]: info: Adding Signal Path Element [object Object] Apr 14 22:02:56 closetotheedge volumio[8722]: info: Adding fusiondspeq DSP Signal Path Element Apr 14 22:02:56 closetotheedge volumio[8722]: info: FusionDsp - ---- installed callbackRead Apr 14 22:02:56 closetotheedge volumio[8722]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:02:56 closetotheedge volumio[8722]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 14 22:02:56 closetotheedge volumio[8722]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 14 22:02:56 closetotheedge volumio[8722]: at onErrorNT (internal/child_process.js:465:16) Apr 14 22:02:56 closetotheedge volumio[8722]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 14 22:02:56 closetotheedge volumio[8722]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 14 22:02:56 closetotheedge volumio[8722]: at listOnTimeout (internal/timers.js:523:9) Apr 14 22:02:56 closetotheedge volumio[8722]: at processTimers (internal/timers.js:497:7) { Apr 14 22:02:56 closetotheedge volumio[8722]: errno: -2, Apr 14 22:02:56 closetotheedge volumio[8722]: code: 'ENOENT', Apr 14 22:02:56 closetotheedge volumio[8722]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 14 22:02:56 closetotheedge volumio[8722]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 14 22:02:56 closetotheedge volumio[8722]: spawnargs: [ Apr 14 22:02:56 closetotheedge volumio[8722]: '-p', Apr 14 22:02:56 closetotheedge volumio[8722]: 9876, Apr 14 22:02:56 closetotheedge volumio[8722]: '-o', Apr 14 22:02:56 closetotheedge volumio[8722]: '/tmp/camilladsp.log', Apr 14 22:02:56 closetotheedge volumio[8722]: '-l', Apr 14 22:02:56 closetotheedge volumio[8722]: 'warn', Apr 14 22:02:56 closetotheedge volumio[8722]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 14 22:02:56 closetotheedge volumio[8722]: ] Apr 14 22:02:56 closetotheedge volumio[8722]: } Apr 14 22:02:56 closetotheedge volumio[8722]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 22:02:56 closetotheedge systemd[1]: Started FusionDsp Daemon. Apr 14 22:02:56 closetotheedge sudo[9679]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:56 closetotheedge volumio[9681]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 14 22:02:56 closetotheedge systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 14 22:02:56 closetotheedge systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 14 22:02:57 closetotheedge sudo[9554]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:57 closetotheedge sudo[9691]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Apr 14 22:02:57 closetotheedge sudo[9691]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 22:02:57 closetotheedge sudo[9691]: pam_unix(sudo:session): session closed for user root Apr 14 22:02:57 closetotheedge sudo[9695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 22:01 Apr 14 22:02:57 closetotheedge sudo[9695]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"