-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-04-23 19:09:59 CEST. -- Apr 23 19:08:03 volumio5 systemd-timedated[1356]: Changed local time to Wed Apr 23 19:08:03 2025 Apr 23 19:08:03 volumio5 sudo[1354]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:03 volumio5 systemd[1]: Starting Daily apt download activities... Apr 23 19:08:03 volumio5 volumio-time-update[804]: volumio-time-update-util: System time updated successfully. Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 23 19:08:03 volumio5 systemd[1]: Started Volumio Time Update Utility. Apr 23 19:08:03 volumio5 systemd[1]: Reached target Multi-User System. Apr 23 19:08:03 volumio5 systemd[1]: Reached target Graphical Interface. Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: A device disappeared from network Apr 23 19:08:03 volumio5 systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: A device disappeared from network Apr 23 19:08:03 volumio5 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 23 19:08:03 volumio5 systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: adding 5f643ee5-3918-4144-bffa-aaf5bb29685d Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: Found device Volumio_5 Apr 23 19:08:03 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:03 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: this is already registered, 5f643ee5-3918-4144-bffa-aaf5bb29685d Apr 23 19:08:03 volumio5 volumio[1049]: info: Discovery: Found device Volumio_5 Apr 23 19:08:03 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:03 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:03 volumio5 ntpd[946]: Listen normally on 4 eth0 192.168.71.50:123 Apr 23 19:08:03 volumio5 ntpd[946]: new interface(s) found: waking up resolver Apr 23 19:08:03 volumio5 systemd[1]: apt-daily.service: Succeeded. Apr 23 19:08:03 volumio5 systemd[1]: Started Daily apt download activities. Apr 23 19:08:04 volumio5 systemd[1]: Starting Daily apt upgrade and clean activities... Apr 23 19:08:04 volumio5 systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 23 19:08:04 volumio5 systemd[1]: Started Daily apt upgrade and clean activities. Apr 23 19:08:04 volumio5 systemd[1]: Startup finished in 11.106s (kernel) + 13.460s (userspace) = 24.566s. Apr 23 19:08:05 volumio5 volumio-remote-updater[799]: [2025-04-23 19:08:05] [connect] Successful connection Apr 23 19:08:05 volumio5 volumio-remote-updater[799]: [2025-04-23 19:08:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745428085 101 Apr 23 19:08:05 volumio5 volumio[1049]: 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: 3 Apr 23 19:08:05 volumio5 volumio[1049]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 23 19:08:09 volumio5 volumio[1049]: info: Volumio Network Manager: Network status updated: 1 Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin bluetooth to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin multiroom to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin metavolumio to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin cd_controller to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 23 19:08:09 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:09 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:09 volumio5 volumio[1049]: info: Starting MyVolumio Remote Streaming Endpoints Apr 23 19:08:09 volumio5 volumio[1049]: info: MyVolumio login type: Token Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 23 19:08:09 volumio5 volumio[1049]: info: Starting Streaming Service Transparent Proxy Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 23 19:08:09 volumio5 volumio[1049]: info: Streaming services startup Apr 23 19:08:09 volumio5 volumio[1049]: info: Starting Streaming Daemon Apr 23 19:08:09 volumio5 sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 19:08:09 volumio5 sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:09 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 23 19:08:09 volumio5 sudo[1480]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:09 volumio5 sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 19:08:09 volumio5 sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:09 volumio5 sudo[1488]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:09 volumio5 volumio[1049]: error: Cannot start Volumio Streaming Daemon Apr 23 19:08:09 volumio5 volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 23 19:08:09 volumio5 volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 23 19:08:09 volumio5 sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 19:08:09 volumio5 sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:09 volumio5 sudo[1490]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:09 volumio5 sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 23 19:08:09 volumio5 sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:09 volumio5 sudo[1494]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:09 volumio5 systemd[1]: Started UPnP Renderer front-end to MPD. Apr 23 19:08:09 volumio5 volumio[1049]: info: Upmpdcli Daemon Started Apr 23 19:08:09 volumio5 volumio[1496]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 23 19:08:09 volumio5 volumio[1049]: STREAMING PROXY: Starting server on port 3245 Apr 23 19:08:09 volumio5 volumio[1049]: Node JS runtime: 14 Apr 23 19:08:10 volumio5 volumio[1049]: error: MyVolumio Custom Token format not valid, refreshing it Apr 23 19:08:10 volumio5 volumio[1049]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.799&uuid=7285857c50f3af9b529344874228f771" http://updates.volumio.org/downloader-v1/track-device Apr 23 19:08:10 volumio5 volumio[1049]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 23 19:08:10 volumio5 volumio[1049]: Dload Upload Total Spent Left Speed Apr 23 19:08:10 volumio5 volumio[1049]: [132B blob data] Apr 23 19:08:10 volumio5 volumio[1049]: retrying in 5 seconds, trial 0 Apr 23 19:08:10 volumio5 volumio[1049]: info: Volumio Calling Home Apr 23 19:08:10 volumio5 volumio[1049]: info: Volumio called home Apr 23 19:08:10 volumio5 volumio[1049]: info: MyVolumio login type: Token Apr 23 19:08:11 volumio5 volumio[1049]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 23 19:08:11 volumio5 volumio[1049]: info: MyVolumio token set successfully Apr 23 19:08:11 volumio5 volumio[1049]: info: MYVOLUMIO: Adding device Apr 23 19:08:11 volumio5 volumio[1049]: info: MYVOLUMIO: Evaluating Server Apr 23 19:08:11 volumio5 volumio[1049]: info: MyVolumio Plan changed: premium Apr 23 19:08:11 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 23 19:08:11 volumio5 volumio[1049]: info: Removing browser output: myVolumio user plan is not superstar Apr 23 19:08:11 volumio5 volumio[1049]: info: Removing audio output: Apr 23 19:08:11 volumio5 volumio[1049]: info: MYVOLUMIO: Adding device Apr 23 19:08:11 volumio5 volumio[1049]: info: MYVOLUMIO: Evaluating Server Apr 23 19:08:11 volumio5 volumio[1049]: info: Remote config written successfully Apr 23 19:08:11 volumio5 volumio[1049]: info: Starting Tunnel 1 Apr 23 19:08:11 volumio5 volumio[1049]: info: Starting Tunnel Connection Checker Apr 23 19:08:11 volumio5 volumio[1049]: info: Completed starting MyVolumio Plugin Apr 23 19:08:12 volumio5 volumio[1049]: info: MYVolumio Device enabled Apr 23 19:08:12 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 23 19:08:12 volumio5 volumio[1049]: info: MyVolumio status changed Apr 23 19:08:12 volumio5 volumio[1049]: info: Streaming services startup Apr 23 19:08:12 volumio5 volumio[1049]: info: Starting Streaming Daemon Apr 23 19:08:12 volumio5 sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 19:08:12 volumio5 sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:12 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 23 19:08:12 volumio5 volumio[1049]: error: [MyVolumio PluginManager] Cache data is invalid! Apr 23 19:08:12 volumio5 sudo[1548]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:12 volumio5 volumio[1049]: error: Cannot start Volumio Streaming Daemon Apr 23 19:08:12 volumio5 volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 23 19:08:12 volumio5 volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 23 19:08:12 volumio5 volumio[1049]: info: Setting Geolocation for MyVolumio to eu3 Apr 23 19:08:12 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:12 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:12 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:12 volumio5 volumio[1049]: info: Setting Geolocation for MyVolumio to eu2 Apr 23 19:08:12 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:12 volumio5 volumio[1049]: info: Successfully Added MyVolumio device Apr 23 19:08:12 volumio5 volumio[1049]: info: Successfully Added MyVolumio device Apr 23 19:08:13 volumio5 volumio[1049]: info: Updating MyVolumio device info Apr 23 19:08:13 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:13 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:13 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:13 volumio5 volumio[1049]: info: Updating MyVolumio device info Apr 23 19:08:13 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:13 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 23 19:08:13 volumio5 volumio[1049]: info: [1745428093560] Starting BluetoothController Apr 23 19:08:13 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 23 19:08:13 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 23 19:08:13 volumio5 sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 23 19:08:13 volumio5 sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:13 volumio5 sudo[1551]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:13 volumio5 volumio[1049]: info: MRS: MultiRoom plugin initialized Apr 23 19:08:13 volumio5 volumio[1049]: info: MRS: STOPPING SNAPCLIENT Apr 23 19:08:13 volumio5 volumio[1049]: info: MRS: Snap server stop Apr 23 19:08:13 volumio5 sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 23 19:08:13 volumio5 sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:13 volumio5 volumio[1049]: info: MRS: STOPPING volumioStreaming Apr 23 19:08:14 volumio5 sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 23 19:08:14 volumio5 sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 23 19:08:14 volumio5 sudo[1567]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1570]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 23 19:08:14 volumio5 sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 23 19:08:14 volumio5 sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1572]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 sudo[1576]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 23 19:08:14 volumio5 volumio[1049]: info: Preparing to generate the ALSA configuration file Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 23 19:08:14 volumio5 volumio[1049]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 23 19:08:14 volumio5 volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 23 19:08:14 volumio5 volumio[1049]: info: Reading ALSA contributions from plugins. Apr 23 19:08:14 volumio5 volumio[1049]: info: Successfully Updated MyVolumio device Apr 23 19:08:14 volumio5 volumio[1049]: info: Successfully Updated MyVolumio device Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: Removed streaming files Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: volumioStreaming STOPPED Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: SNAPSERVER STOPPED Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: SNAPCLIENT STOPPED Apr 23 19:08:14 volumio5 volumio[1049]: info: Asound.conf file written Apr 23 19:08:14 volumio5 sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 23 19:08:14 volumio5 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1579]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: Output device has changed, restarting MPD Apr 23 19:08:14 volumio5 volumio[1049]: info: Output device has changed, restarting Shairport Sync Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:14 volumio5 sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 19:08:14 volumio5 sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1584]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 19:08:14 volumio5 sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 systemd[1]: Stopping Music Player Daemon... Apr 23 19:08:14 volumio5 systemd[1]: mpd.service: Succeeded. Apr 23 19:08:14 volumio5 systemd[1]: Stopped Music Player Daemon. Apr 23 19:08:14 volumio5 volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output for this device Apr 23 19:08:14 volumio5 systemd[1]: Starting Music Player Daemon... Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding audio output: Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding audio output: Apr 23 19:08:14 volumio5 sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio_5 Apr 23 19:08:14 volumio5 sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 volumio[1049]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding METAVOLUMIO REST API Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 23 19:08:14 volumio5 volumio[1049]: info: Preparing CD Folders Apr 23 19:08:14 volumio5 sudo[1596]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding CD REST API Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 23 19:08:14 volumio5 volumio[1049]: info: Starting UDEV Watcher for CD Apr 23 19:08:14 volumio5 volumio[1049]: info: Detecting CD presence with UDEV Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 23 19:08:14 volumio5 sudo[1592]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 19:08:14 volumio5 sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 sudo[1592]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding inputs REST Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 23 19:08:14 volumio5 volumio[1049]: info: Scanning Audio Inputs Apr 23 19:08:14 volumio5 volumio[1049]: info: Checking against Known Cards name Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding Server instance for streaming Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 23 19:08:14 volumio5 volumio[1049]: error: Hi Res Audio Failed Login: Missing Login Data Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 23 19:08:14 volumio5 volumio[1049]: info: Refreshing TIDAL token Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 23 19:08:14 volumio5 volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 23 19:08:14 volumio5 volumio[1049]: info: Stopping AccessToken refresher cron for QOBUZ Apr 23 19:08:14 volumio5 sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:08:14 volumio5 sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 volumio[1049]: info: AccessToken refresher cron started for QOBUZ Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding QOBUZ REST API Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 23 19:08:14 volumio5 volumio[1049]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 23 19:08:14 volumio5 volumio[1049]: info: Bluetooth name changed to Volumio_5 Apr 23 19:08:14 volumio5 sudo[1606]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 23 19:08:14 volumio5 volumio[1049]: info: MPD Permissions set Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:14 volumio5 volumio[1049]: info: Starting Shairport Sync Apr 23 19:08:14 volumio5 sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 19:08:14 volumio5 sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:14 volumio5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 23 19:08:14 volumio5 systemd[1]: shairport-sync.service: Succeeded. Apr 23 19:08:14 volumio5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 23 19:08:14 volumio5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 23 19:08:14 volumio5 sudo[1616]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: info: Shairport-Sync Started Apr 23 19:08:14 volumio5 mpd[1602]: Apr 23 19:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 23 19:08:14 volumio5 systemd[1]: Started Music Player Daemon. Apr 23 19:08:14 volumio5 sudo[1586]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:14 volumio5 volumio[1049]: error: updateQueue error: null Apr 23 19:08:14 volumio5 volumio[1049]: info: Access Token successfully retrieved Apr 23 19:08:14 volumio5 volumio[1049]: info: Successfully retrieved User Session From TIDAL Apr 23 19:08:14 volumio5 volumio[1049]: info: Successfully retrieved User Subscription From TIDAL Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding TIDAL to Browse Sources Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 23 19:08:14 volumio5 volumio[1049]: info: [1745428094977] CoreMusicLibrary::Adding element TIDAL Apr 23 19:08:14 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 19:08:14 volumio5 volumio[1049]: Cannot find translation for source TIDAL Apr 23 19:08:14 volumio5 volumio[1049]: info: Stopping AccessToken refresher cron Apr 23 19:08:14 volumio5 volumio[1049]: info: AccessToken refresher cron started Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding TIDAL REST API Endpoints Apr 23 19:08:14 volumio5 volumio[1049]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 23 19:08:16 volumio5 sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 23 19:08:16 volumio5 sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:16 volumio5 systemd[1]: Started MyVolumio SSH Tunnel. Apr 23 19:08:16 volumio5 sudo[1629]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:16 volumio5 volumio[1049]: info: Remote SSH Started Apr 23 19:08:16 volumio5 autossh[1632]: port set to 0, monitoring disabled Apr 23 19:08:16 volumio5 autossh[1632]: starting ssh (count 1) Apr 23 19:08:16 volumio5 autossh[1632]: ssh child pid is 1635 Apr 23 19:08:17 volumio5 volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:17 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:17 volumio5 volumiossh-tunnel[1631]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Apr 23 19:08:17 volumio5 sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 23 19:08:17 volumio5 sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:17 volumio5 systemd[1]: Started Volumio Bluetooth Module. Apr 23 19:08:17 volumio5 sudo[1637]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:17 volumio5 volumio[1049]: info: Volumio BT Module successfully started Apr 23 19:08:17 volumio5 sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 23 19:08:17 volumio5 sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:17 volumio5 volumiobt[1639]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 23 19:08:17 volumio5 sudo[1640]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:17 volumio5 volumio[1049]: info: TidalConnect service stoped! Apr 23 19:08:17 volumio5 volumio[1049]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 23 19:08:17 volumio5 volumio[1049]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 23 19:08:17 volumio5 sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 23 19:08:17 volumio5 sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:17 volumio5 systemd[1]: Started Volumio Tidal Connect Service. Apr 23 19:08:17 volumio5 sudo[1652]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:17 volumio5 volumio[1049]: info: Executing endpoint tc_getconfig Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 23 19:08:17 volumio5 vtcs[1654]: STARTING TidalConnect services, version: 1.5.0.45 Apr 23 19:08:17 volumio5 vtcs[1654]: STARTED TidalConnect services. Apr 23 19:08:17 volumio5 volumio[1049]: info: Executing endpoint tc_connect Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 23 19:08:17 volumio5 volumio[1049]: info: Connecting to TidalConnect Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::servicePushState Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreStateMachine::pushState Apr 23 19:08:17 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 23 19:08:17 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output update for this device Apr 23 19:08:17 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output Apr 23 19:08:17 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:17 volumio5 volumio[1049]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::servicePushState Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreStateMachine::pushState Apr 23 19:08:17 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:17 volumio5 volumio[1049]: info: CoreCommandRouter::volumioPushState Apr 23 19:08:17 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output update for this device Apr 23 19:08:17 volumio5 volumio[1049]: info: MRS: Pushing multiroomSync output Apr 23 19:08:17 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:17 volumio5 volumio[1049]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 23 19:08:18 volumio5 sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 23 19:08:18 volumio5 sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:18 volumio5 volumiobt[1639]: pulseaudio: no process found Apr 23 19:08:18 volumio5 sudo[1673]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:18 volumio5 dbus-daemon[795]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1677 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 23 19:08:18 volumio5 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 23 19:08:18 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:08:18 volumio5 dbus-daemon[795]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 23 19:08:18 volumio5 systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Successfully called chroot. Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Successfully dropped privileges. Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Successfully limited resources. Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Running. Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Canary thread running. Apr 23 19:08:18 volumio5 dbus-daemon[795]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1678 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 23 19:08:18 volumio5 rtkit-daemon[1678]: Watchdog thread running. Apr 23 19:08:18 volumio5 systemd[1]: Starting Authorization Manager... Apr 23 19:08:18 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:08:18 volumio5 polkitd[1682]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 23 19:08:18 volumio5 dbus-daemon[795]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 23 19:08:18 volumio5 systemd[1]: Started Authorization Manager. Apr 23 19:08:18 volumio5 pulseaudio[1677]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 23 19:08:18 volumio5 pulseaudio[1677]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 23 19:08:18 volumio5 volumiobt[1639]: Applying permissions Apr 23 19:08:18 volumio5 volumiobt[1639]: Setting BT discoverable and pairable Apr 23 19:08:18 volumio5 bluetoothd[960]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Apr 23 19:08:18 volumio5 bluetoothd[960]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Apr 23 19:08:18 volumio5 pulseaudio[1677]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 23 19:08:18 volumio5 volumiobt[1639]: Agent registered Apr 23 19:08:18 volumio5 volumiobt[1639]: [91B blob data] Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# -e power on Apr 23 19:08:18 volumio5 volumiobt[1639]: Invalid command in menu main: -e Apr 23 19:08:18 volumio5 volumiobt[1639]: Apr 23 19:08:18 volumio5 volumiobt[1639]: Use "help" for a list of available commands in a menu. Apr 23 19:08:18 volumio5 volumiobt[1639]: Use "menu " if you want to enter any submenu. Apr 23 19:08:18 volumio5 volumiobt[1639]: Use "back" if you want to return to menu main. Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# agent on Apr 23 19:08:18 volumio5 volumiobt[1639]: Agent is already registered Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# discoverable on Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# pairable on Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# agent NoInputNoOutput Apr 23 19:08:18 volumio5 volumiobt[1639]: Agent is already registered Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# default-agent Apr 23 19:08:18 volumio5 volumiobt[1639]: [bluetooth]# quit Apr 23 19:08:18 volumio5 kernel: Bluetooth: RFCOMM TTY layer initialized Apr 23 19:08:18 volumio5 kernel: Bluetooth: RFCOMM socket layer initialized Apr 23 19:08:18 volumio5 kernel: Bluetooth: RFCOMM ver 1.11 Apr 23 19:08:18 volumio5 volumiobt[1639]: [59B blob data] Apr 23 19:08:19 volumio5 volumio[1049]: info: MRS: Getting audio outputs on start Apr 23 19:08:19 volumio5 volumio[1049]: info: MRS: Requesting all other devices output Apr 23 19:08:20 volumio5 volumio[1049]: info: TidalConnect service started! Apr 23 19:08:20 volumio5 volumio[1049]: [Metrics] CommandRouter: 22s 111.46ms Apr 23 19:08:20 volumio5 volumio[1049]: info: CoreCommandRouter::volumiosetStartupVolume Apr 23 19:08:20 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:20 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:20 volumio5 volumio[1049]: info: CoreCommandRouter::Close All Modals sent Apr 23 19:08:20 volumio5 volumio[1049]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 23 19:08:20 volumio5 volumio[1049]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 23 19:08:20 volumio5 volumio[1049]: info: camilladsp stopping service pid 1288... Apr 23 19:08:20 volumio5 volumio[1049]: info: camilladsp service terminated, instance 1 Apr 23 19:08:20 volumio5 volumio[1049]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 23 19:08:20 volumio5 volumio[1049]: info: FusionDsp - else 1 -2.5 Apr 23 19:08:20 volumio5 volumio[1049]: info: camilladsp service started and running in background, instance 1 Apr 23 19:08:21 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 23 19:08:21 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 23 19:08:21 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:08:21 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 23 19:08:22 volumio5 systemd[1]: systemd-fsckd.service: Succeeded. Apr 23 19:08:23 volumio5 systemd[1]: systemd-hostnamed.service: Succeeded. Apr 23 19:08:24 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 19:08:24 volumio5 volumio[1049]: 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 23 19:08:24 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:24 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:27 volumio5 volumio[1049]: info: BOOT COMPLETED Apr 23 19:08:27 volumio5 volumio[1496]: ......................................................................................................................................................................................................................................................................................................................................................................................++++ Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources Apr 23 19:08:29 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 19:08:30 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 19:08:30 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:08:30 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 19:08:30 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 23 19:08:30 volumio5 volumio-remote-updater[799]: No test mode Apr 23 19:08:30 volumio5 volumio-remote-updater[799]: No alpha test mode Apr 23 19:08:30 volumio5 volumio[1049]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 23 19:08:30 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 23 19:08:31 volumio5 volumio[1496]: .........................................................................++++ Apr 23 19:08:31 volumio5 volumio[1496]: e is 65537 (0x010001) Apr 23 19:08:31 volumio5 volumio[1496]: writing RSA key Apr 23 19:08:33 volumio5 systemd[1]: systemd-timedated.service: Succeeded. Apr 23 19:08:49 volumio5 sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 19:08:49 volumio5 sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:49 volumio5 sudo[1783]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:49 volumio5 sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 19:08:49 volumio5 sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:49 volumio5 sudo[1785]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:49 volumio5 sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 19:08:49 volumio5 sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:49 volumio5 sudo[1803]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:49 volumio5 sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 19:08:49 volumio5 sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:08:49 volumio5 sudo[1805]: pam_unix(sudo:session): session closed for user root Apr 23 19:08:49 volumio5 volumio[1049]: verbose: New Socket.io Connection to 192.168.71.50 from 192.168.71.24 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetVisibleSources Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:49 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 23 19:08:49 volumio5 volumio[1049]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 23 19:08:49 volumio5 volumio[1049]: info: Received Get System Info Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 19:08:49 volumio5 volumio[1049]: info: Discovery: Getting this device information Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:49 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:49 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:49 volumio5 volumio[1049]: info: Listing playlists Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 19:08:49 volumio5 volumio[1049]: info: FusionDsp - no preset used Apr 23 19:08:49 volumio5 volumio[1049]: info: FusionDsp - Apr 23 19:08:49 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:51 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 19:08:51 volumio5 volumio[1049]: info: Received Get System Info Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 19:08:51 volumio5 volumio[1049]: info: Discovery: Getting this device information Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:51 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:51 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 19:08:53 volumio5 volumio[1049]: info: Received Get System Info Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 19:08:53 volumio5 volumio[1049]: info: Discovery: Getting this device information Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:08:53 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 19:08:53 volumio5 volumio[1049]: info: Executing endpoint metavolumio Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 19:08:53 volumio5 volumio[1049]: info: Executing endpoint metavolumio Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 19:08:53 volumio5 volumio[1049]: info: Executing endpoint metavolumio Apr 23 19:08:53 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 19:08:54 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 19:08:54 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 23 19:08:54 volumio5 volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 19:08:54 volumio5 volumio[1049]: info: FusionDsp - no preset used Apr 23 19:08:54 volumio5 volumio[1049]: info: FusionDsp - Apr 23 19:08:59 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 23 19:08:59 volumio5 ntpd[946]: Soliciting pool server 217.196.104.131 Apr 23 19:09:00 volumio5 ntpd[946]: Soliciting pool server 217.24.20.5 Apr 23 19:09:00 volumio5 ntpd[946]: Soliciting pool server 195.178.58.245 Apr 23 19:09:00 volumio5 ntpd[946]: Soliciting pool server 147.91.8.1 Apr 23 19:09:01 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 23 19:09:01 volumio5 ntpd[946]: Soliciting pool server 147.91.26.20 Apr 23 19:09:01 volumio5 ntpd[946]: Soliciting pool server 147.91.26.20 Apr 23 19:09:01 volumio5 ntpd[946]: Soliciting pool server 147.91.26.20 Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 19:09:08 volumio5 volumio[1049]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 23 19:09:08 volumio5 volumio[1049]: info: Received Get System Version Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 19:09:08 volumio5 volumio[1049]: info: Received Get System Info Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 19:09:08 volumio5 volumio[1049]: info: Discovery: Getting this device information Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::volumioGetState Apr 23 19:09:08 volumio5 volumio[1049]: info: CorePlayQueue::getTrack 0 Apr 23 19:09:08 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 19:09:19 volumio5 volumio[1049]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 23 19:09:22 volumio5 volumio[1049]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 23 19:09:22 volumio5 volumio[1049]: info: FusionDsp - Stopping FusionDsp service Apr 23 19:09:22 volumio5 volumio[1049]: info: camilladsp stopping service pid 1724... Apr 23 19:09:22 volumio5 volumio[1049]: info: camilladsp service terminated, instance 1 Apr 23 19:09:22 volumio5 sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 23 19:09:22 volumio5 sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:22 volumio5 systemd[1]: Stopping FusionDsp Daemon... Apr 23 19:09:22 volumio5 volumio[1296]: Backend configuration: Apr 23 19:09:22 volumio5 volumio[1296]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 23 19:09:22 volumio5 volumio[1296]: active_config_txt: null Apr 23 19:09:22 volumio5 volumio[1296]: camilla_host: 127.0.0.1 Apr 23 19:09:22 volumio5 volumio[1296]: camilla_port: 9876 Apr 23 19:09:22 volumio5 volumio[1296]: coeff_dir: /data/INTERNAL/FusionDsp/filters Apr 23 19:09:22 volumio5 volumio[1296]: config_dir: /data/configuration/audio_interface/fusiondsp Apr 23 19:09:22 volumio5 volumio[1296]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 23 19:09:22 volumio5 volumio[1296]: log_file: /tmp/camilladsp.log Apr 23 19:09:22 volumio5 volumio[1296]: on_get_active_config: null Apr 23 19:09:22 volumio5 volumio[1296]: on_set_active_config: null Apr 23 19:09:22 volumio5 volumio[1296]: port: 5011 Apr 23 19:09:22 volumio5 volumio[1296]: supported_capture_types: null Apr 23 19:09:22 volumio5 volumio[1296]: supported_playback_types: null Apr 23 19:09:22 volumio5 volumio[1296]: update_config_symlink: false Apr 23 19:09:22 volumio5 volumio[1296]: update_config_txt: false Apr 23 19:09:22 volumio5 volumio[1296]: ======== Running on http://0.0.0.0:5011 ======== Apr 23 19:09:22 volumio5 volumio[1296]: (Press CTRL+C to quit) Apr 23 19:09:22 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:22 volumio5 systemd[1]: fusiondsp.service: Succeeded. Apr 23 19:09:22 volumio5 systemd[1]: Stopped FusionDsp Daemon. Apr 23 19:09:22 volumio5 sudo[1901]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:22 volumio5 volumio[1049]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 23 19:09:22 volumio5 volumio[1049]: info: Removing fusiondspeq DSP Signal Path Element Apr 23 19:09:22 volumio5 volumio[1049]: info: Check plugin dependencies Apr 23 19:09:22 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 19:09:22 volumio5 volumio[1049]: info: Rename folder Apr 23 19:09:22 volumio5 volumio[1049]: info: Folder /tmp/downloaded_plugin.zip removed Apr 23 19:09:22 volumio5 volumio[1049]: info: Move to category Apr 23 19:09:23 volumio5 volumio[1049]: info: Checking if install.sh is present Apr 23 19:09:23 volumio5 volumio[1049]: info: Executing install.sh Apr 23 19:09:23 volumio5 sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 23 19:09:23 volumio5 sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:23 volumio5 volumio[1049]: info: creating filters folder and copying demo filters Apr 23 19:09:23 volumio5 volumio[1049]: info: copying demo flters Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/ Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/ Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 23 19:09:23 volumio5 sudo[1940]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 23 19:09:23 volumio5 sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/PEQ/ Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/convfir/ Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/convfir/kef.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/convfir/test.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/ Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 23 19:09:23 volumio5 volumio[1049]: info: Installing/fusiondsp dependencies Apr 23 19:09:23 volumio5 volumio[1049]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease Apr 23 19:09:23 volumio5 volumio[1049]: info: Get:2 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Apr 23 19:09:23 volumio5 volumio[1049]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Apr 23 19:09:23 volumio5 volumio[1049]: info: Fetched 15.0 kB in 1s (29.4 kB/s) Apr 23 19:09:25 volumio5 volumio[1049]: info: Reading package lists... Apr 23 19:09:25 volumio5 volumio[1049]: info: Building dependency tree... Apr 23 19:09:25 volumio5 volumio[1049]: info: Reading state information... Apr 23 19:09:25 volumio5 volumio[1049]: info: All packages are up to date. Apr 23 19:09:25 volumio5 sudo[1940]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:25 volumio5 sudo[2141]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 23 19:09:25 volumio5 sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:25 volumio5 volumio[1049]: info: Reading package lists... Apr 23 19:09:25 volumio5 volumio[1049]: info: Building dependency tree... Apr 23 19:09:25 volumio5 volumio[1049]: info: Reading state information... Apr 23 19:09:26 volumio5 volumio[1049]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Apr 23 19:09:26 volumio5 sudo[2141]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:26 volumio5 volumio[1049]: info: python3-pip is already the newest version (18.1-5+rpt1). Apr 23 19:09:26 volumio5 volumio[1049]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Apr 23 19:09:26 volumio5 sudo[2145]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Apr 23 19:09:26 volumio5 sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:26 volumio5 volumio[1049]: info: lib/systemd/system/fusiondsp.service Apr 23 19:09:26 volumio5 sudo[2145]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:27 volumio5 volumio[1049]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Apr 23 19:09:28 volumio5 volumio[1049]: info: more info at http://www.winimage.com/zLibDll/unzip.html Apr 23 19:09:28 volumio5 volumio[1049]: info: Apr 23 19:09:28 volumio5 volumio[1049]: info: cgui-1.0.0.zip opened Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/LICENSE.txt Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/README.md Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/build/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/robots.txt Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/logo512.png Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/asset-manifest.json Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/index.html Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/.put_statics_here Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/favicon.ico Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/logo192.png Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/manifest.json Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/css-variables.css Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/service-worker.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/build/static/ Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/build/static/css/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/main.1503f6cd.css Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/build/static/media/ Apr 23 19:09:28 volumio5 sudo[2149]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Apr 23 19:09:28 volumio5 sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/build/static/js/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.71e11955.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.71e11955.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/config/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/config/camillagui.yml Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/config/gui-config.yml Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/backend/ Apr 23 19:09:28 volumio5 sudo[2149]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/filters_test.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/version.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/filters.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/settings.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/views.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/filterdefaults.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__init__.py Apr 23 19:09:28 volumio5 volumio[1049]: info: creating directory: cgui/backend/__pycache__/ Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/routes.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/backend/filemanagement.py Apr 23 19:09:28 volumio5 volumio[1049]: info: extracting: cgui/main.py Apr 23 19:09:28 volumio5 sudo[2151]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Apr 23 19:09:28 volumio5 sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:28 volumio5 sudo[2151]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:28 volumio5 sudo[2153]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Apr 23 19:09:28 volumio5 sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:28 volumio5 sudo[2153]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:29 volumio5 volumio[1049]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Apr 23 19:09:29 volumio5 volumio[1049]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Apr 23 19:09:29 volumio5 volumio[1049]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-z6vzmzir Apr 23 19:09:30 volumio5 volumio[1049]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Apr 23 19:09:30 volumio5 volumio[1049]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Apr 23 19:09:30 volumio5 volumio[1049]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Apr 23 19:09:30 volumio5 volumio[1049]: info: Building wheels for collected packages: camilladsp Apr 23 19:09:30 volumio5 volumio[1049]: info: Running setup.py bdist_wheel for camilladsp: started Apr 23 19:09:31 volumio5 volumio[1049]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Apr 23 19:09:31 volumio5 volumio[1049]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-6sqd2mzo/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Apr 23 19:09:31 volumio5 volumio[1049]: info: Successfully built camilladsp Apr 23 19:09:32 volumio5 volumio[1049]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Apr 23 19:09:32 volumio5 volumio[1049]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Apr 23 19:09:32 volumio5 volumio[1049]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-8h3ld_6i Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Apr 23 19:09:33 volumio5 volumio[1049]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Apr 23 19:09:33 volumio5 volumio[1049]: info: Building wheels for collected packages: camilladsp-plot Apr 23 19:09:33 volumio5 volumio[1049]: info: Running setup.py bdist_wheel for camilladsp-plot: started Apr 23 19:09:33 volumio5 volumio[1049]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Apr 23 19:09:33 volumio5 volumio[1049]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-zt40txhi/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Apr 23 19:09:33 volumio5 volumio[1049]: info: Successfully built camilladsp-plot Apr 23 19:09:34 volumio5 volumio[1049]: info: copying hw detection script Apr 23 19:09:34 volumio5 volumio[1049]: info: Detected cpu architecture as aarch64 Apr 23 19:09:35 volumio5 sudo[2231]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Apr 23 19:09:35 volumio5 sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:35 volumio5 sudo[2231]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:35 volumio5 sudo[2233]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Apr 23 19:09:35 volumio5 sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:35 volumio5 sudo[2233]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:35 volumio5 sudo[2235]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Apr 23 19:09:35 volumio5 sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:35 volumio5 volumio[1049]: info: Reading package lists... Apr 23 19:09:35 volumio5 volumio[1049]: info: Building dependency tree... Apr 23 19:09:35 volumio5 volumio[1049]: info: Reading state information... Apr 23 19:09:36 volumio5 volumio[1049]: info: drc is already the newest version (3.2.2~dfsg0-2). Apr 23 19:09:36 volumio5 sudo[2235]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:36 volumio5 sudo[1915]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:36 volumio5 volumio[1049]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Apr 23 19:09:36 volumio5 volumio[1049]: info: Install script completed Apr 23 19:09:36 volumio5 volumio[1049]: info: Done installing plugin. Apr 23 19:09:36 volumio5 volumio[1049]: info: Enabling plugin fusiondsp Apr 23 19:09:36 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 19:09:36 volumio5 volumio[1049]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false}] Apr 23 19:09:36 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 23 19:09:36 volumio5 volumio[1049]: info: Folder /tmp/plugins removed Apr 23 19:09:36 volumio5 volumio[1049]: info: Folder /tmp/downloaded_plugin.zip removed Apr 23 19:09:36 volumio5 volumio[1049]: Plugin install end detected on script Apr 23 19:09:36 volumio5 volumio[1049]: info: Folder /data/temp removed Apr 23 19:09:36 volumio5 volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 19:09:36 volumio5 volumio[1049]: info: FusionDsp - no preset used Apr 23 19:09:36 volumio5 volumio[1049]: info: FusionDsp - Apr 23 19:09:57 volumio5 volumio[1049]: info: Enabling plugin fusiondsp Apr 23 19:09:57 volumio5 volumio[1049]: info: Loading plugin "fusiondsp"... Apr 23 19:09:57 volumio5 volumio[1049]: info: Applying required configuration parameters for plugin fusiondsp Apr 23 19:09:57 volumio5 volumio[1049]: info: Preparing to generate the ALSA configuration file Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: Reading ALSA contributions from plugins. Apr 23 19:09:57 volumio5 volumio[1049]: info: Asound.conf file unchanged, so no further update is needed Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting MPD Apr 23 19:09:57 volumio5 sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 19:09:57 volumio5 sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting Shairport Sync Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 sudo[2309]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 19:09:57 volumio5 sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 systemd[1]: Stopping Music Player Daemon... Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 systemd[1]: mpd.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Music Player Daemon. Apr 23 19:09:57 volumio5 vtcs[1654]: [2025-04-23 19:09:57.359] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 23 19:09:57 volumio5 vtcs[1654]: [2025-04-23 19:09:57.359] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 23 19:09:57 volumio5 volumio[1049]: info: PLUGIN START: fusiondsp Apr 23 19:09:57 volumio5 volumio[1049]: info: Loading i18n strings for locale en Apr 23 19:09:57 volumio5 systemd[1]: Starting Music Player Daemon... Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: FusionDsp - mixtype--------------------- Hardware Apr 23 19:09:57 volumio5 volumio[1049]: info: Preparing to generate the ALSA configuration file Apr 23 19:09:57 volumio5 sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: info: Done. Apr 23 19:09:57 volumio5 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 23 19:09:57 volumio5 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 23 19:09:57 volumio5 systemd[1]: vtcs.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: Reading ALSA contributions from plugins. Apr 23 19:09:57 volumio5 sudo[2321]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 sudo[2324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2318]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 19:09:57 volumio5 sudo[2318]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 sudo[2318]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 sudo[2324]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 19:09:57 volumio5 volumio[1049]: info: MPD Permissions set Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: FusionDsp - no preset used Apr 23 19:09:57 volumio5 volumio[1049]: info: FusionDsp - Apr 23 19:09:57 volumio5 volumio[1049]: info: Starting Shairport Sync Apr 23 19:09:57 volumio5 volumio[1049]: info: Asound.conf file unchanged, so no further update is needed Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting MPD Apr 23 19:09:57 volumio5 sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 19:09:57 volumio5 sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 19:09:57 volumio5 sudo[2338]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2338]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 23 19:09:57 volumio5 systemd[1]: shairport-sync.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting Shairport Sync Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 19:09:57 volumio5 sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 sudo[2335]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 23 19:09:57 volumio5 systemd[1]: mpd.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Music Player Daemon. Apr 23 19:09:57 volumio5 systemd[1]: Starting Music Player Daemon... Apr 23 19:09:57 volumio5 volumio[1049]: info: Preparing to generate the ALSA configuration file Apr 23 19:09:57 volumio5 sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 23 19:09:57 volumio5 volumio[1049]: info: Reading ALSA contributions from plugins. Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 sudo[2348]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 19:09:57 volumio5 sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2348]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 sudo[2353]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: info: MPD Permissions set Apr 23 19:09:57 volumio5 volumio[1049]: info: Shairport-Sync Started Apr 23 19:09:57 volumio5 sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2356]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: Starting Shairport Sync Apr 23 19:09:57 volumio5 volumio[1049]: info: Asound.conf file written Apr 23 19:09:57 volumio5 sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 19:09:57 volumio5 sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 23 19:09:57 volumio5 sudo[2370]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 23 19:09:57 volumio5 sudo[2370]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 systemd[1]: shairport-sync.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting MPD Apr 23 19:09:57 volumio5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 sudo[2367]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 sudo[2376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 19:09:57 volumio5 sudo[2376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2376]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: info: Output device has changed, restarting Shairport Sync Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 sudo[2381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 19:09:57 volumio5 sudo[2381]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 23 19:09:57 volumio5 systemd[1]: mpd.service: Succeeded. Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 19:09:57 volumio5 volumio[1049]: xcb_connection_has_error() returned true Apr 23 19:09:57 volumio5 volumio[1049]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 systemd[1]: Stopped Music Player Daemon. Apr 23 19:09:57 volumio5 sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 volumio[1049]: info: MPD Permissions set Apr 23 19:09:57 volumio5 volumio[1049]: info: Shairport-Sync Started Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 sudo[2393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 23 19:09:57 volumio5 systemd[1]: Starting Music Player Daemon... Apr 23 19:09:57 volumio5 sudo[2393]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: info: Starting Shairport Sync Apr 23 19:09:57 volumio5 sudo[2393]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 sudo[2390]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 19:09:57 volumio5 sudo[2386]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 19:09:57 volumio5 sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 19:09:57 volumio5 sudo[2386]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 23 19:09:57 volumio5 systemd[1]: shairport-sync.service: Succeeded. Apr 23 19:09:57 volumio5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 19:09:57 volumio5 volumio[1049]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 19:09:57 volumio5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 23 19:09:57 volumio5 sudo[2401]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:57 volumio5 volumio[1049]: info: Shairport-Sync Started Apr 23 19:09:58 volumio5 mpd[2405]: Apr 23 19:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 23 19:09:58 volumio5 systemd[1]: Started Music Player Daemon. Apr 23 19:09:58 volumio5 sudo[2381]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:58 volumio5 sudo[2311]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:58 volumio5 sudo[2342]: pam_unix(sudo:session): session closed for user root Apr 23 19:09:58 volumio5 volumio[1049]: error: MPD error: The expression evaluated to a falsy value: Apr 23 19:09:58 volumio5 volumio[1049]: assert.ok(self.idling) Apr 23 19:09:58 volumio5 volumio[1049]: error: The expression evaluated to a falsy value: Apr 23 19:09:58 volumio5 volumio[1049]: assert.ok(self.idling) Apr 23 19:09:58 volumio5 volumio[1049]: error: MPD error: The expression evaluated to a falsy value: Apr 23 19:09:58 volumio5 volumio[1049]: assert.ok(self.idling) Apr 23 19:09:58 volumio5 volumio[1049]: error: The expression evaluated to a falsy value: Apr 23 19:09:58 volumio5 volumio[1049]: assert.ok(self.idling) Apr 23 19:09:58 volumio5 volumio[1049]: error: updateQueue error: null Apr 23 19:09:59 volumio5 volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 19:09:59 volumio5 volumio[1049]: TypeError: Cannot read property 'off' of undefined Apr 23 19:09:59 volumio5 volumio[1049]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Apr 23 19:09:59 volumio5 volumio[1049]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 23 19:09:59 volumio5 volumio[1049]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 23 19:09:59 volumio5 volumio[1049]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 23 19:09:59 volumio5 volumio[1049]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Apr 23 19:09:59 volumio5 volumio[1049]: at Socket.emit (events.js:315:20) Apr 23 19:09:59 volumio5 volumio[1049]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 23 19:09:59 volumio5 volumio[1049]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 23 19:09:59 volumio5 volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 19:09:59 volumio5 sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 19:08 Apr 23 19:09:59 volumio5 sudo[2420]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"