-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-09-09 15:20:12 CEST. -- Sep 09 15:19:26 minidsp-shd ntpd[811]: receive: Unexpected origin timestamp 0xeacc22ae.84e75ee3 does not match aorg 0000000000.00000000 from server@79.160.225.150 xmt 0xec6aa7de.9c12f474 Sep 09 15:19:26 minidsp-shd ntpd[811]: receive: Unexpected origin timestamp 0xeacc22ae.850a369a does not match aorg 0000000000.00000000 from server@193.150.22.36 xmt 0xec6aa7de.9cb19102 Sep 09 15:19:26 minidsp-shd ntpd[811]: receive: Unexpected origin timestamp 0xeacc22ae.84fe7d5c does not match aorg 0000000000.00000000 from server@193.150.22.56 xmt 0xec6aa7de.9c9b9519 Sep 09 15:19:26 minidsp-shd systemd[1]: Starting Daily apt download activities... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "network"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Refreshing Cached IP Addresses Sep 09 15:19:27 minidsp-shd sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 15:19:27 minidsp-shd sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "services"... Sep 09 15:19:27 minidsp-shd sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 15:19:27 minidsp-shd sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "alsa_controller"... Sep 09 15:19:27 minidsp-shd sudo[858]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:27 minidsp-shd sudo[862]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:27 minidsp-shd sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 09 15:19:27 minidsp-shd sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:27 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "wizard"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "networkfs"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Starting Udev Watcher for removable devices Sep 09 15:19:27 minidsp-shd sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.197/USB /mnt/NAS/USB Sep 09 15:19:27 minidsp-shd sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:27 minidsp-shd kernel: FS-Cache: Loaded Sep 09 15:19:27 minidsp-shd volumio[784]: info: Ignoring mount for partition: BOOT Sep 09 15:19:27 minidsp-shd volumio[784]: info: Ignoring mount for partition: volumio Sep 09 15:19:27 minidsp-shd volumio[784]: info: Ignoring mount for partition: volumio_data Sep 09 15:19:27 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "volumio_command_line_client"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "upnp"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: [1757423967778] Starting Upmpd Daemon Sep 09 15:19:27 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "my_music"... Sep 09 15:19:27 minidsp-shd volumio[784]: info: Loading plugin "mpd"... Sep 09 15:19:27 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Sep 09 15:19:27 minidsp-shd kernel: Key type cifs.spnego registered Sep 09 15:19:27 minidsp-shd kernel: Key type cifs.idmap registered Sep 09 15:19:27 minidsp-shd kernel: CIFS: Attempting to mount //192.168.10.197/USB Sep 09 15:19:27 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Sep 09 15:19:28 minidsp-shd sudo[882]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:28 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Sep 09 15:19:28 minidsp-shd systemd[1]: Started Daily apt download activities. Sep 09 15:19:28 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Sep 09 15:19:28 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Sep 09 15:19:28 minidsp-shd volumio[784]: info: Loading plugin "upnp_browser"... Sep 09 15:19:28 minidsp-shd sh[383]: timed out Sep 09 15:19:28 minidsp-shd sh[383]: dhcpcd exited Sep 09 15:19:28 minidsp-shd dhcpcd[431]: timed out Sep 09 15:19:28 minidsp-shd sh[383]: ifup: failed to bring up eth0 Sep 09 15:19:28 minidsp-shd systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 09 15:19:28 minidsp-shd dhcpcd[431]: dhcpcd exited Sep 09 15:19:28 minidsp-shd systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 09 15:19:29 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 09 15:19:29 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Sep 09 15:19:29 minidsp-shd volumio[784]: info: Loading plugin "bluetooth"... Sep 09 15:19:30 minidsp-shd volumio[784]: info: [1757423970051] Starting BluetoothController Sep 09 15:19:30 minidsp-shd volumio[784]: info: Loading plugin "alarm-clock"... Sep 09 15:19:30 minidsp-shd volumio[784]: info: Loading plugin "manifestui"... Sep 09 15:19:31 minidsp-shd volumio[784]: info: Loading plugin "metavolumio"... Sep 09 15:19:31 minidsp-shd sudo[864]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:33 minidsp-shd volumio[784]: info: Loading plugin "airplay_emulation"... Sep 09 15:19:33 minidsp-shd volumio[784]: info: Starting Shairport Sync Sep 09 15:19:33 minidsp-shd volumio[784]: info: Loading plugin "cd_controller"... Sep 09 15:19:34 minidsp-shd volumio[784]: info: Loading plugin "last_100"... Sep 09 15:19:34 minidsp-shd volumio[784]: info: Loading plugin "raat"... Sep 09 15:19:34 minidsp-shd volumio[784]: info: RAAT Plugin loaded Sep 09 15:19:34 minidsp-shd volumio[784]: info: Adding restartRAATSocket REST API Endpoint Sep 09 15:19:34 minidsp-shd volumio[784]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Sep 09 15:19:34 minidsp-shd volumio[784]: info: Loading plugin "streaming_services"... Sep 09 15:19:36 minidsp-shd volumio[784]: info: Starting Streaming Service Transparent Proxy Sep 09 15:19:36 minidsp-shd volumio[784]: info: Loading plugin "tidalconnect"... Sep 09 15:19:37 minidsp-shd volumio[784]: info: Loading plugin "webradio"... Sep 09 15:19:37 minidsp-shd volumio[784]: info: Loading plugin "i2s_dacs"... Sep 09 15:19:37 minidsp-shd volumio[784]: info: I2S DAC not set, start Auto-detection Sep 09 15:19:37 minidsp-shd volumio[784]: info: Loading plugin "volumiodiscovery"... Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** For more information see Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 15:19:37 minidsp-shd volumio[784]: *** WARNING *** For more information see Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** For more information see Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 15:19:37 minidsp-shd node[784]: *** WARNING *** For more information see Sep 09 15:19:37 minidsp-shd volumio-remote-updater[467]: [2025-09-09 15:19:37] [connect] Successful connection Sep 09 15:19:37 minidsp-shd volumio[784]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 09 15:19:37 minidsp-shd volumio[784]: info: Discovery: Started advertising with name: miniDSP SHD Sep 09 15:19:37 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 15:19:37 minidsp-shd volumio[784]: info: Loading plugin "spop"... Sep 09 15:19:39 minidsp-shd volumio[784]: STREAMING PROXY: Starting server on port 3245 Sep 09 15:19:39 minidsp-shd volumio[784]: Node JS runtime: 14 Sep 09 15:19:40 minidsp-shd volumio[784]: info: Loading plugin "multiroom"... Sep 09 15:19:43 minidsp-shd volumio[784]: info: Applying required configuration parameters for plugin multiroom Sep 09 15:19:43 minidsp-shd sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 09 15:19:43 minidsp-shd sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:43 minidsp-shd sudo[991]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:44 minidsp-shd volumio[784]: info: MRS: MultiRoom plugin initialized Sep 09 15:19:44 minidsp-shd volumio[784]: info: MRS: STOPPING SNAPCLIENT Sep 09 15:19:44 minidsp-shd volumio[784]: info: MRS: Snap server stop Sep 09 15:19:44 minidsp-shd sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 09 15:19:44 minidsp-shd sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:44 minidsp-shd volumio[784]: info: MRS: STOPPING volumioStreaming Sep 09 15:19:44 minidsp-shd sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 09 15:19:44 minidsp-shd sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:44 minidsp-shd sudo[1007]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:44 minidsp-shd sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 09 15:19:44 minidsp-shd sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:44 minidsp-shd volumio[784]: info: Loading plugin "outputs"... Sep 09 15:19:44 minidsp-shd sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 09 15:19:44 minidsp-shd sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:44 minidsp-shd volumio[784]: info: Loading plugin "albumart"... Sep 09 15:19:44 minidsp-shd sudo[1015]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:44 minidsp-shd sudo[1009]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:44 minidsp-shd volumio[784]: info: Loading plugin "ms_surface_dial"... Sep 09 15:19:44 minidsp-shd sudo[1012]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:44 minidsp-shd volumio[784]: Forking 3 albumart workers Sep 09 15:19:45 minidsp-shd volumio[784]: info: Applying required configuration parameters for plugin ms_surface_dial Sep 09 15:19:45 minidsp-shd volumio[784]: info: Plugin example_plugin is not enabled Sep 09 15:19:45 minidsp-shd volumio[784]: info: Loading plugin "hi_res_audio"... Sep 09 15:19:46 minidsp-shd volumio[784]: Starting albumart workers Sep 09 15:19:46 minidsp-shd volumio[784]: Starting albumart workers Sep 09 15:19:46 minidsp-shd volumio[784]: Starting albumart workers Sep 09 15:19:48 minidsp-shd volumio[784]: info: Applying required configuration parameters for plugin hi_res_audio Sep 09 15:19:48 minidsp-shd volumio[784]: info: Loading plugin "inputs"... Sep 09 15:19:50 minidsp-shd volumio[784]: info: Loading plugin "qobuz"... Sep 09 15:19:52 minidsp-shd volumio[784]: info: Loading plugin "tidal"... Sep 09 15:19:52 minidsp-shd volumio-remote-updater[467]: [2025-09-09 15:19:52] [connect] Successful connection Sep 09 15:19:55 minidsp-shd volumio[784]: info: Loading plugin "oem_helper"... Sep 09 15:19:56 minidsp-shd volumio[784]: info: Applying required configuration parameters for plugin oem_helper Sep 09 15:19:56 minidsp-shd volumio[784]: info: Loading plugin "updater_comm"... Sep 09 15:19:57 minidsp-shd volumio[784]: info: Plugin mpdemulation is not enabled Sep 09 15:19:57 minidsp-shd volumio[784]: info: Loading plugin "rest_api"... Sep 09 15:19:57 minidsp-shd volumio[784]: info: Loading plugin "websocket"... Sep 09 15:19:57 minidsp-shd volumio[784]: info: Loading i18n strings for locale en Sep 09 15:19:57 minidsp-shd volumio[784]: Updating browse sources language Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::initPlayerControls Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 15:19:57 minidsp-shd volumio[784]: Express server listening on port 3000 Sep 09 15:19:57 minidsp-shd volumio[784]: [Metrics] WebUI: 37s 469.61ms Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreStateMachine::resetVolumioState Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreStateMachine::getcurrentVolume Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:19:57 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 15:19:57 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:19:58 minidsp-shd sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 15:19:58 minidsp-shd sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Sep 09 15:19:58 minidsp-shd sudo[1080]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:58 minidsp-shd sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 15:19:58 minidsp-shd sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:58 minidsp-shd sudo[1082]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:58 minidsp-shd volumio[784]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 09 15:19:58 minidsp-shd volumio[784]: info: Completed loading Core Plugins Sep 09 15:19:58 minidsp-shd volumio[784]: info: Preparing to generate the ALSA configuration file Sep 09 15:19:58 minidsp-shd volumio[784]: info: Cannot read play queue from file Sep 09 15:19:58 minidsp-shd volumio[784]: info: MRS: Removed streaming files Sep 09 15:19:58 minidsp-shd volumio[784]: info: MRS: volumioStreaming STOPPED Sep 09 15:19:58 minidsp-shd volumio[784]: info: MRS: SNAPSERVER STOPPED Sep 09 15:19:58 minidsp-shd volumio[784]: info: MRS: SNAPCLIENT STOPPED Sep 09 15:19:58 minidsp-shd volumio[784]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 09 15:19:58 minidsp-shd volumio[784]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 09 15:19:58 minidsp-shd volumio[784]: info: Reading ALSA contributions from plugins. Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreStateMachine::setRepeat false single undefined Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:19:58 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreStateMachine::setRandom null Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:19:58 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:19:58 minidsp-shd volumio[784]: info: Setting Device type: NanoPi NEO 3 Sep 09 15:19:58 minidsp-shd volumio[784]: info: Asound.conf file unchanged, so no further update is needed Sep 09 15:19:58 minidsp-shd volumio[784]: info: Output device has changed, restarting MPD Sep 09 15:19:58 minidsp-shd sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 09 15:19:58 minidsp-shd sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:58 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Sep 09 15:19:58 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 15:19:58 minidsp-shd volumio[784]: info: Output device has changed, restarting Shairport Sync Sep 09 15:19:58 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:58 minidsp-shd sudo[1091]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:58 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:19:58 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:59 minidsp-shd sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 15:19:59 minidsp-shd sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:59 minidsp-shd systemd[1]: Listening on mpd.socket. Sep 09 15:19:59 minidsp-shd volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 15:19:59 minidsp-shd systemd[1]: Starting Music Player Daemon... Sep 09 15:19:59 minidsp-shd volumio[784]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:59 minidsp-shd volumio[784]: info: ___________ START PLUGINS ___________ Sep 09 15:19:59 minidsp-shd volumio[784]: info: ControllerMpd::onStart: Initializing MPD Sep 09 15:19:59 minidsp-shd volumio[784]: info: Creating MPD Configuration file Sep 09 15:19:59 minidsp-shd sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 15:19:59 minidsp-shd sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:19:59 minidsp-shd volumio[784]: info: [1757423999274] CoreMusicLibrary::Adding element Media Servers Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:19:59 minidsp-shd sudo[1107]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:59 minidsp-shd sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 15:19:59 minidsp-shd sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 15:19:59 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 09 15:19:59 minidsp-shd systemd[1]: mpd.service: Succeeded. Sep 09 15:19:59 minidsp-shd systemd[1]: Stopped Music Player Daemon. Sep 09 15:19:59 minidsp-shd systemd[1]: Starting Music Player Daemon... Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding Manifest REST API Endpoints Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding METAVOLUMIO REST API Endpoints Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 09 15:19:59 minidsp-shd sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:19:59 minidsp-shd sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:59 minidsp-shd sudo[1113]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:59 minidsp-shd volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:19:59 minidsp-shd volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 15:19:59 minidsp-shd volumio[784]: info: Preparing CD Folders Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding CD REST API Endpoints Sep 09 15:19:59 minidsp-shd volumio[784]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 09 15:19:59 minidsp-shd volumio[784]: info: Starting UDEV Watcher for CD Sep 09 15:19:59 minidsp-shd volumio[784]: info: Detecting CD presence with UDEV Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:19:59 minidsp-shd volumio[784]: info: [1757423999689] CoreMusicLibrary::Adding element Last_100 Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:19:59 minidsp-shd volumio[784]: info: Starting RAAT Plugin Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 09 15:19:59 minidsp-shd volumio[784]: info: Additional UI Settings Added for plugin music_service/raat Sep 09 15:19:59 minidsp-shd volumio[784]: info: Streaming services startup Sep 09 15:19:59 minidsp-shd volumio[784]: info: Starting Streaming Daemon Sep 09 15:19:59 minidsp-shd sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 15:19:59 minidsp-shd sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:19:59 minidsp-shd sudo[1147]: pam_unix(sudo:session): session closed for user root Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:19:59 minidsp-shd volumio[784]: info: [1757423999994] CoreMusicLibrary::Adding element Webradio Sep 09 15:19:59 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 15:20:00 minidsp-shd sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 09 15:20:00 minidsp-shd sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:00 minidsp-shd volumio[784]: info: Creating Spotify config file Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:00 minidsp-shd sudo[1167]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 09 15:20:00 minidsp-shd volumio[784]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:00 minidsp-shd dbus-daemon[465]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=784 comm="/usr/bin/node /volumio/index.js ") Sep 09 15:20:00 minidsp-shd volumio[784]: error: Hi Res Audio Failed Login: Missing Login Data Sep 09 15:20:00 minidsp-shd systemd[1]: Starting Bluetooth service... Sep 09 15:20:00 minidsp-shd volumio[784]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 09 15:20:00 minidsp-shd volumio[784]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 09 15:20:00 minidsp-shd bluetoothd[1173]: Bluetooth daemon 5.23 Sep 09 15:20:00 minidsp-shd dbus-daemon[465]: [system] Successfully activated service 'org.bluez' Sep 09 15:20:00 minidsp-shd systemd[1]: Started Bluetooth service. Sep 09 15:20:00 minidsp-shd bluetoothd[1173]: Starting SDP server Sep 09 15:20:00 minidsp-shd bluetoothd[1173]: kernel lacks bnep-protocol support Sep 09 15:20:00 minidsp-shd bluetoothd[1173]: System does not support network plugin Sep 09 15:20:00 minidsp-shd dbus-daemon[465]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1173 comm="/usr/local/libexec/bluetooth/bluetoothd ") Sep 09 15:20:00 minidsp-shd bluetoothd[1173]: Bluetooth management interface 1.14 initialized Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 15:20:00 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:20:00 minidsp-shd volumio[784]: info: Volumio Calling Home Sep 09 15:20:00 minidsp-shd systemd[1]: Starting Hostname Service... Sep 09 15:20:00 minidsp-shd volumio[784]: info: Stopping AccessToken refresher cron for QOBUZ Sep 09 15:20:00 minidsp-shd dbus-daemon[465]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 09 15:20:00 minidsp-shd systemd[1]: Started Hostname Service. Sep 09 15:20:01 minidsp-shd volumio[784]: info: AccessToken refresher cron started for QOBUZ Sep 09 15:20:01 minidsp-shd volumio[784]: info: Stopping AccessToken refresher cron Sep 09 15:20:01 minidsp-shd volumio[784]: info: AccessToken refresher cron started Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding TIDAL REST API Endpoints Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding QOBUZ REST API Endpoints Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 09 15:20:01 minidsp-shd volumio[784]: info: Discovery: adding 7de2fe78-6056-4e2f-9f1b-de3300fc121e Sep 09 15:20:01 minidsp-shd volumio[784]: info: Discovery: Found device miniDSP SHD Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioGetState Sep 09 15:20:01 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:01 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output for this device Sep 09 15:20:01 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding audio output: Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding audio output: Sep 09 15:20:01 minidsp-shd volumio[784]: info: Serial port opened successfully Sep 09 15:20:01 minidsp-shd volumio[784]: error: Cannot start Volumio Streaming Daemon Sep 09 15:20:01 minidsp-shd volumio[784]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 15:20:01 minidsp-shd volumio[784]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 15:20:01 minidsp-shd volumio[784]: info: RAAT Albumart path created successfully Sep 09 15:20:01 minidsp-shd volumio[784]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Sep 09 15:20:01 minidsp-shd volumio[784]: Can't get device info: No such device Sep 09 15:20:01 minidsp-shd volumio[784]: info: MPD Permissions set Sep 09 15:20:01 minidsp-shd volumio[784]: info: MPD Permissions set Sep 09 15:20:01 minidsp-shd volumio[784]: info: Upmpdcli Daemon Started Sep 09 15:20:01 minidsp-shd volumio[784]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10.5,"disableVolumeControl":false} Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:01 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:01 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:01 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 09 15:20:01 minidsp-shd volumio[784]: info: Ignoring ROON Volume update because of undefined data Sep 09 15:20:01 minidsp-shd volumio[784]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Sep 09 15:20:01 minidsp-shd volumio[784]: info: [msSurfaceDial init()] check /org/bluez Sep 09 15:20:01 minidsp-shd volumio[784]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Sep 09 15:20:01 minidsp-shd volumio[784]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::Reload Ui Sep 09 15:20:01 minidsp-shd volumio[784]: info: Volumio called home Sep 09 15:20:01 minidsp-shd volumio[784]: info: Spotify config file written Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Sep 09 15:20:01 minidsp-shd volumio[784]: info: updateDSP function in raat called! Sep 09 15:20:01 minidsp-shd volumio[784]: info: Updating RAAT Signal Path Sep 09 15:20:01 minidsp-shd volumio[784]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Sep 09 15:20:01 minidsp-shd volumio[784]: info: Reconfiguring and Restarting RAAT Plugin Sep 09 15:20:01 minidsp-shd sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 09 15:20:01 minidsp-shd sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd systemd[1]: Started go-librespot Daemon. Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 15:20:01 minidsp-shd go-librespot[1197]: go-librespot daemon starting... Sep 09 15:20:01 minidsp-shd volumio[784]: info: Not Reporting Auto name since its the default one Sep 09 15:20:01 minidsp-shd volumio[784]: info: RAAT Overriding default device vendor model Sep 09 15:20:01 minidsp-shd volumio[784]: info: Adding MINIDSP Inputs Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:20:01 minidsp-shd volumio[784]: info: [1757424001794] CoreMusicLibrary::Adding element Inputs Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:20:01 minidsp-shd sudo[1191]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:20:01 minidsp-shd volumio[784]: info: [1757424001803] CoreMusicLibrary::Adding element Presets Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:20:01 minidsp-shd volumio[784]: Cannot find translation for source Presets Sep 09 15:20:01 minidsp-shd volumio[784]: (node:784) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Sep 09 15:20:01 minidsp-shd volumio[784]: (Use `node --trace-deprecation ...` to show where the warning was created) Sep 09 15:20:01 minidsp-shd volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:20:01 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=info msg="running go-librespot 0.3.2" Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=debug msg="app state loaded" Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:02 minidsp-shd volumio[784]: info: No need to fix Spotify hosts Sep 09 15:20:02 minidsp-shd sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 09 15:20:02 minidsp-shd sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:02 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Sep 09 15:20:02 minidsp-shd sudo[1218]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:02 minidsp-shd volumio[784]: info: MRS: Found cast device: Chromecast-Audio-c65dfbdf7844aacbe482ac820860cb13 Sep 09 15:20:02 minidsp-shd volumio[784]: info: Adding audio output: Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:20:02 minidsp-shd volumio[784]: Cannot find translation for source Presets Sep 09 15:20:02 minidsp-shd sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 09 15:20:02 minidsp-shd sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:02 minidsp-shd volumiobt[1220]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 09 15:20:02 minidsp-shd sudo[1221]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:02 minidsp-shd volumio[784]: info: Volumio BT Module successfully started Sep 09 15:20:02 minidsp-shd volumio[784]: info: Starting Shairport Sync Sep 09 15:20:02 minidsp-shd volumio[784]: info: Starting Shairport Sync Sep 09 15:20:02 minidsp-shd volumio[784]: info: Starting Shairport Sync Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 09 15:20:02 minidsp-shd sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 15:20:02 minidsp-shd sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:02 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:02+02:00" level=info msg="zeroconf server listening on port 36793" Sep 09 15:20:02 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 09 15:20:02 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 09 15:20:02 minidsp-shd sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 15:20:03 minidsp-shd sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:03 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Sep 09 15:20:03 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 09 15:20:03 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 09 15:20:03 minidsp-shd sudo[1227]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:03 minidsp-shd sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 15:20:03 minidsp-shd sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:03 minidsp-shd volumio[784]: xcb_connection_has_error() returned true Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="obtained new client token: AABnBxO3nZlC2bozfEz5PjRcCn/1pBV4J8vOpiWl1IsOnW2ZC44K77fb+OWvsA5BrKZEY7J6egxSvai4kdVx0YMaUWy92yc10KVJ/XO3NXya7j/Fs3m/TbH31nXaopXUXrsQ1scbhklqtdeMRQ8HK5b+lMdkHokO1bpYV9EmDKQX8rrK4th0WTy/WVdyN+CS8ZdhETSjNrG6mKnyQ/QFHsNrlBnwBfLniLZYTQhIaPlpOiNyy3dpKo1i" Sep 09 15:20:03 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 09 15:20:03 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Sep 09 15:20:03 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 09 15:20:03 minidsp-shd volumio[784]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 09 15:20:03 minidsp-shd volumio[784]: SPOTIFY: BQDBeWh0IixmlK1JxWq_G-iWFvh6OBLlVW7kjE-Ve_Ryws5BKDIAbbBs2AXnLCUm5ygUqIXNKiv2djCgRvWiqf5if1436gCzXaLXzMLDYTGZzqC_htDUycCBmy0J_YYIId3HZ_x9zq0DrqsnMGzbv1ABn_UecxF3Ue418jTq-gKXwDCV8y5SNipqdiOQIWCQ6tWeW_cYGUm0kTJ7s7YgTTnHoUOYQvX677Iw_6iY5y5JlCjXC0eAukCEixS7RpxbBFUFDrBi_npOwffUWGCniPvRu48 Sep 09 15:20:03 minidsp-shd volumio[784]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 09 15:20:03 minidsp-shd volumio[784]: info: New Spotify access token = BQDBeWh0IixmlK1JxWq_G-iWFvh6OBLlVW7kjE-Ve_Ryws5BKDIAbbBs2AXnLCUm5ygUqIXNKiv2djCgRvWiqf5if1436gCzXaLXzMLDYTGZzqC_htDUycCBmy0J_YYIId3HZ_x9zq0DrqsnMGzbv1ABn_UecxF3Ue418jTq-gKXwDCV8y5SNipqdiOQIWCQ6tWeW_cYGUm0kTJ7s7YgTTnHoUOYQvX677Iw_6iY5y5JlCjXC0eAukCEixS7RpxbBFUFDrBi_npOwffUWGCniPvRu48 Sep 09 15:20:03 minidsp-shd volumio[784]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 09 15:20:03 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 09 15:20:03 minidsp-shd sudo[1229]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:03 minidsp-shd sudo[1232]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:03 minidsp-shd sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 09 15:20:03 minidsp-shd sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="completed keyexchange" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="completed challenge" Sep 09 15:20:03 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 15:20:03 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=info msg="authenticated AP" username="11******46" Sep 09 15:20:03 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 15:20:03 minidsp-shd systemd[1]: Started RAAT DAEMON. Sep 09 15:20:03 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 15:20:03 minidsp-shd sudo[1240]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:03 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=info msg="authenticated Login5" username="11******46" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="initializing zeroconf session" username="11******46" Sep 09 15:20:03 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 09 15:20:03 minidsp-shd volumio[784]: info: Enabling external Volume Control Sep 09 15:20:03 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:03 minidsp-shd volumio[784]: info: MRS: Found cast device: Altibox-TV-435455ce13cad7e711055c9a6762ab85 Sep 09 15:20:03 minidsp-shd volumio[784]: info: Adding audio output: Sep 09 15:20:03 minidsp-shd sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 09 15:20:03 minidsp-shd sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="dealer connection opened" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=trace msg="starting accesspoint recv loop" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=trace msg="starting dealer recv loop" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=trace msg="received accesspoint ping" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="received connection id: MjhkOWVhMmQtYjBi...NDQ0OERBMkY0Ng==" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 09 15:20:03 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 09 15:20:03 minidsp-shd volumiobt[1220]: pulseaudio: no process found Sep 09 15:20:03 minidsp-shd sudo[1255]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:03 minidsp-shd volumio[784]: info: Raat Daemon started successfully Sep 09 15:20:03 minidsp-shd volumio[784]: info: Shairport-Sync Started Sep 09 15:20:03 minidsp-shd volumio[784]: Error adding Membership: Error: addMembership EINVAL Sep 09 15:20:03 minidsp-shd volumio[784]: info: Shairport-Sync Started Sep 09 15:20:03 minidsp-shd volumio[784]: info: Shairport-Sync Started Sep 09 15:20:03 minidsp-shd volumio[784]: info: TidalConnect service stoped! Sep 09 15:20:04 minidsp-shd dbus-daemon[465]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1263 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 09 15:20:04 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 09 15:20:04 minidsp-shd volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="update volume requested to 65535/65535" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=trace msg="received accesspoint pong ack" Sep 09 15:20:04 minidsp-shd dbus-daemon[465]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 09 15:20:04 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Successfully called chroot. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Successfully dropped privileges. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Successfully limited resources. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Running. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Watchdog thread running. Sep 09 15:20:04 minidsp-shd rtkit-daemon[1264]: Canary thread running. Sep 09 15:20:04 minidsp-shd dbus-daemon[465]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1264 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 09 15:20:04 minidsp-shd systemd[1]: Starting Authorization Manager... Sep 09 15:20:04 minidsp-shd volumio[784]: info: Executing endpoint restartRAATSocket Sep 09 15:20:04 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=trace msg="emitting websocket event: volume" Sep 09 15:20:04 minidsp-shd polkitd[1267]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 09 15:20:04 minidsp-shd dbus-daemon[465]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 09 15:20:04 minidsp-shd systemd[1]: Started Authorization Manager. Sep 09 15:20:04 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 15:20:04 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 15:20:04 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioGetState Sep 09 15:20:04 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="handling transfer player command from 85757369114ddcc12e2aecd4fe342a061bb73c6b" Sep 09 15:20:04 minidsp-shd volumio[784]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 09 15:20:04 minidsp-shd volumio[784]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:47As5L47btaUDbmaO3j3lP" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=trace msg="fetched new page 0 with 43 items (list: 43)" uri="spotify:playlist:47As5L47btaUDbmaO3j3lP" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="shuffled context with seed 16757258337034189878 (len: 43, keep: 3)" uri="spotify:playlist:47As5L47btaUDbmaO3j3lP" Sep 09 15:20:04 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:04+02:00" level=debug msg="loading track (paused: true, position: 6644ms)" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:04 minidsp-shd sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 09 15:20:04 minidsp-shd sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=trace msg="emitting websocket event: will_play" Sep 09 15:20:05 minidsp-shd volumio[784]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10.5,"mute":false,"disableVolumeControl":false} Sep 09 15:20:05 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:05 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:05 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 15:20:05 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:05 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:05 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:05 minidsp-shd volumio[784]: error: Help! Some callbacks for volumioPushState are crashing! Sep 09 15:20:05 minidsp-shd volumio[784]: error: Cannot read property 'sendVolumeMute' of undefined Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2051" Sep 09 15:20:05 minidsp-shd volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Sep 09 15:20:05 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Sep 09 15:20:05 minidsp-shd sudo[1278]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="selected format OGG_VORBIS_320 (de38305738a05ba922569fec1a450e89f9bfaf69)" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="requested aes key for file de38305738a05ba922569fec1a450e89f9bfaf69, gid: 2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd volumio[784]: info: MRS: Getting audio outputs on start Sep 09 15:20:05 minidsp-shd volumio[784]: info: MRS: Requesting all other devices output Sep 09 15:20:05 minidsp-shd volumio[784]: info: go-librespot daemon successfully initialized Sep 09 15:20:05 minidsp-shd pulseaudio[1263]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 09 15:20:05 minidsp-shd pulseaudio[1263]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 09 15:20:05 minidsp-shd volumiobt[1220]: Applying permissions Sep 09 15:20:05 minidsp-shd volumiobt[1220]: Setting BT discoverable and pairable Sep 09 15:20:05 minidsp-shd pulseaudio[1263]: 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 Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [38B blob data] Sep 09 15:20:05 minidsp-shd volumiobt[1220]: Invalid command Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# agent on Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# discoverable on Sep 09 15:20:05 minidsp-shd volumiobt[1220]: No default controller available Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# pairable on Sep 09 15:20:05 minidsp-shd volumiobt[1220]: No default controller available Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# agent NoInputNoOutput Sep 09 15:20:05 minidsp-shd volumiobt[1220]: Failed to register agent object Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# default-agent Sep 09 15:20:05 minidsp-shd volumiobt[1220]: No agent is registered Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [bluetooth]# quit Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [61B blob data] Sep 09 15:20:05 minidsp-shd volumiobt[1220]: [62B blob data] Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="fetched first chunk of 23, total size is 11965488 bytes" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=trace msg="seek to 6644ms (diff: 159ms, samples: 293000, bytes: 246243)" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="created new output device" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=info msg="loaded track \"Papaya\" (paused: true, position: 6644ms, duration: 304293ms, prefetched: false)" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=trace msg="emitting websocket event: metadata" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=trace msg="emitting websocket event: active" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="sending successful reply for dealer request" Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 09 15:20:05 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:05+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 09 15:20:06 minidsp-shd volumio[784]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10.5,"mute":false,"disableVolumeControl":false} Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:06 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:06 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:06 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:06 minidsp-shd volumio[784]: error: Help! Some callbacks for volumioPushState are crashing! Sep 09 15:20:06 minidsp-shd volumio[784]: error: Cannot read property 'sendVolumeMute' of undefined Sep 09 15:20:06 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:06+02:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 09 15:20:06 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 15:20:06 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:06+02:00" level=trace msg="emitting websocket event: paused" Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioGetState Sep 09 15:20:06 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:06 minidsp-shd volumio[784]: SPOTIFY: User informations: {"country":"NO","display_name":"Bartek Gołędowski","email":"baltona84@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1164747946"},"followers":{"href":null,"total":16},"href":"https://api.spotify.com/v1/users/1164747946","id":"1164747946","images":[{"height":300,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t39.30808-1/468740207_10163603388614369_2448924313253529105_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=107&ccb=1-7&_nc_sid=79bf43&_nc_ohc=gR6WTXgPOO4Q7kNvwFKWCcX&_nc_oc=Adl8daT6_9pP4QK7RxIG2nZXZwVj-owpsBwyJFQ7oZdGMYY_wAw1a_NyAJrSbFVd7Vgq6DCRcAq7jUsysS93mZvk&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=tUgi85A7YFlAKMwyGgEtvA&oh=00_AfaZy91y6rwGk_gw098ZXGr3FALbpJI6ABSxGeeTRGK_sg&oe=68C5B9AA","width":300},{"height":64,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t39.30808-1/468740207_10163603388614369_2448924313253529105_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=107&ccb=1-7&_nc_sid=28885b&_nc_ohc=gR6WTXgPOO4Q7kNvwFKWCcX&_nc_oc=Adl8daT6_9pP4QK7RxIG2nZXZwVj-owpsBwyJFQ7oZdGMYY_wAw1a_NyAJrSbFVd7Vgq6DCRcAq7jUsysS93mZvk&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=tUgi85A7YFlAKMwyGgEtvA&oh=00_AfY_unQkEO-w3uncAlOLNqY_yN8Xu884ophAzM-p_3xnBA&oe=68C5B9AA","width":64}],"product":"premium","type":"user","uri":"spotify:user:1164747946"} Sep 09 15:20:06 minidsp-shd volumio[784]: info: Spotify Successfully logged in Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 15:20:06 minidsp-shd volumio[784]: info: [1757424006444] CoreMusicLibrary::Adding element Spotify Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 15:20:06 minidsp-shd volumio[784]: Cannot find translation for source Presets Sep 09 15:20:06 minidsp-shd volumio[784]: Cannot find translation for source Spotify Sep 09 15:20:06 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:06+02:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:06 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:06+02:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:06 minidsp-shd volumio[784]: info: Executing endpoint tc_getconfig Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 09 15:20:06 minidsp-shd vtcs[1280]: STARTING TidalConnect services, version: 1.3.0.19 Sep 09 15:20:06 minidsp-shd vtcs[1280]: STARTED TidalConnect services. Sep 09 15:20:06 minidsp-shd volumio[784]: info: Executing endpoint tc_connect Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 09 15:20:06 minidsp-shd volumio[784]: info: Connecting to TidalConnect Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::servicePushState Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:06 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:06 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:06 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:06 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 09 15:20:07 minidsp-shd volumio[784]: verbose: CURRENT POSITION 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::syncState stateService stop Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::syncState currentStatus stop Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio[784]: info: No code Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::servicePushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 09 15:20:07 minidsp-shd volumio[784]: verbose: CURRENT POSITION 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::syncState stateService stop Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::syncState currentStatus stop Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio[784]: info: No code Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreStateMachine::pushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:07 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioPushState Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output update for this device Sep 09 15:20:07 minidsp-shd volumio[784]: info: MRS: Pushing multiroomSync output Sep 09 15:20:07 minidsp-shd volumio-remote-updater[467]: [2025-09-09 15:20:07] [connect] Successful connection Sep 09 15:20:07 minidsp-shd volumio-remote-updater[467]: [2025-09-09 15:20:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757424007 101 Sep 09 15:20:07 minidsp-shd volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Sep 09 15:20:08 minidsp-shd volumio[784]: info: TidalConnect service started! Sep 09 15:20:08 minidsp-shd volumio[784]: info: Initializing connection to go-librespot Websocket Sep 09 15:20:08 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:08+02:00" level=debug msg="new websocket client" Sep 09 15:20:08 minidsp-shd volumio[784]: info: Connection to go-librespot Websocket established Sep 09 15:20:08 minidsp-shd mpd[1114]: Sep 09 15:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 09 15:20:08 minidsp-shd systemd[1]: Started Music Player Daemon. Sep 09 15:20:08 minidsp-shd volumio[784]: Upnp client error: Error: This socket has been ended by the other party Sep 09 15:20:08 minidsp-shd volumio[784]: Upnp client error: Error: This socket has been ended by the other party Sep 09 15:20:08 minidsp-shd sudo[1109]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:08 minidsp-shd sudo[1097]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:08 minidsp-shd volumio[784]: info: Completed starting Core Plugins Sep 09 15:20:08 minidsp-shd volumio[784]: info: ------------------------------------------- Sep 09 15:20:08 minidsp-shd volumio[784]: info: ----- MyVolumio plugins startup ---- Sep 09 15:20:08 minidsp-shd volumio[784]: info: ------------------------------------------- Sep 09 15:20:08 minidsp-shd volumio[784]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 09 15:20:08 minidsp-shd volumio[784]: error: MPD error: The expression evaluated to a falsy value: Sep 09 15:20:08 minidsp-shd volumio[784]: assert.ok(self.idling) Sep 09 15:20:08 minidsp-shd volumio[784]: error: The expression evaluated to a falsy value: Sep 09 15:20:08 minidsp-shd volumio[784]: assert.ok(self.idling) Sep 09 15:20:08 minidsp-shd volumio[784]: info: MPD running with PID1114 Sep 09 15:20:08 minidsp-shd volumio[784]: ,establishing connection Sep 09 15:20:08 minidsp-shd volumio[784]: error: updateQueue error: null Sep 09 15:20:08 minidsp-shd volumio[784]: error: updateQueue error: null Sep 09 15:20:09 minidsp-shd volumio[1096]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 09 15:20:09 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:09 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:09 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 15:20:09 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 15:20:09 minidsp-shd volumio[784]: info: Not Reporting Auto name since its the default one Sep 09 15:20:09 minidsp-shd volumio[784]: info: RAAT Overriding default device vendor model Sep 09 15:20:09 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 09 15:20:09 minidsp-shd volumio[784]: xcb_connection_has_error() returned true Sep 09 15:20:09 minidsp-shd sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 09 15:20:09 minidsp-shd sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 15:20:09 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Sep 09 15:20:09 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 09 15:20:09 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Sep 09 15:20:10 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Sep 09 15:20:10 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 15:20:10 minidsp-shd volumio[784]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 15:20:10 minidsp-shd systemd[1]: Started RAAT DAEMON. Sep 09 15:20:10 minidsp-shd sudo[1329]: pam_unix(sudo:session): session closed for user root Sep 09 15:20:10 minidsp-shd volumio[784]: info: Raat Daemon started successfully Sep 09 15:20:10 minidsp-shd volumio[784]: info: Executing endpoint restartRAATSocket Sep 09 15:20:10 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 09 15:20:10 minidsp-shd volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Sep 09 15:20:10 minidsp-shd volumio[784]: info: CoreCommandRouter::volumioGetState Sep 09 15:20:10 minidsp-shd volumio[784]: info: CorePlayQueue::getTrack 0 Sep 09 15:20:10 minidsp-shd volumio[784]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Sep 09 15:20:10 minidsp-shd volumio[784]: info: updateDSP function in raat called! Sep 09 15:20:10 minidsp-shd volumio[784]: info: Updating RAAT Signal Path Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=debug msg="handling resume player command from 85757369114ddcc12e2aecd4fe342a061bb73c6b" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=trace msg="seek to 6644ms (diff: 159ms, samples: 293000, bytes: 246243)" uri="spotify:track:2aGhWZn0GnxdOdSJJVeNwa" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Sep 09 15:20:10 minidsp-shd go-librespot[1197]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 14852 us, period size = 655 frames, buffer time = 1486077 us, buffer size = 65536 frames, periods per buffer = 100 frames" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=debug msg="resume track at 6367ms" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=trace msg="scheduling prefetch in 268s" Sep 09 15:20:10 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:10+02:00" level=debug msg="sending successful reply for dealer request" Sep 09 15:20:11 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 09 15:20:11 minidsp-shd go-librespot[1197]: time="2025-09-09T15:20:11+02:00" level=trace msg="emitting websocket event: playing" Sep 09 15:20:11 minidsp-shd volumio[784]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2aGhWZn0GnxdOdSJJVeNwa","resume":true,"play_origin":"playlist"}} Sep 09 15:20:11 minidsp-shd volumio[784]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 15:20:11 minidsp-shd volumio[784]: TypeError: Cannot read property 'service' of undefined Sep 09 15:20:11 minidsp-shd volumio[784]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Sep 09 15:20:11 minidsp-shd volumio[784]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Sep 09 15:20:11 minidsp-shd volumio[784]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Sep 09 15:20:11 minidsp-shd volumio[784]: at WebSocket.emit (events.js:400:28) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.emit (events.js:400:28) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Sep 09 15:20:11 minidsp-shd volumio[784]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 09 15:20:11 minidsp-shd volumio[784]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Sep 09 15:20:11 minidsp-shd volumio[784]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Sep 09 15:20:11 minidsp-shd volumio[784]: at Socket.emit (events.js:400:28) Sep 09 15:20:11 minidsp-shd volumio[784]: at addChunk (internal/streams/readable.js:293:12) Sep 09 15:20:11 minidsp-shd volumio[784]: at readableAddChunk (internal/streams/readable.js:267:9) Sep 09 15:20:11 minidsp-shd volumio[784]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 15:20:12 minidsp-shd sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-09 15:19 Sep 09 15:20:12 minidsp-shd sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"