-- Logs begin at Fri 2024-03-22 14:13:25 GMT, end at Thu 2025-07-17 17:23:08 BST. -- Jul 17 17:22:21 minidsp-shd ntpd[769]: receive: Unexpected origin timestamp 0xe9a8109e.96a8e14f does not match aorg 0000000000.00000000 from server@131.111.8.61 xmt 0xec23a1bd.7fc121b4 Jul 17 17:22:21 minidsp-shd ntpd[769]: receive: Unexpected origin timestamp 0xe9a8109e.96993e48 does not match aorg 0000000000.00000000 from server@217.154.60.177 xmt 0xec23a1bd.7fe1a622 Jul 17 17:22:21 minidsp-shd systemd[1]: Starting Daily apt download activities... Jul 17 17:22:21 minidsp-shd ntpd[769]: Soliciting pool server 162.159.200.123 Jul 17 17:22:22 minidsp-shd volumio-remote-updater[482]: [2025-07-17 17:22:22] [connect] Successful connection Jul 17 17:22:22 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Jul 17 17:22:22 minidsp-shd systemd[1]: Started Daily apt download activities. Jul 17 17:22:23 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "network"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Refreshing Cached IP Addresses Jul 17 17:22:24 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 17 17:22:24 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Jul 17 17:22:24 minidsp-shd sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 17:22:24 minidsp-shd sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:24 minidsp-shd sudo[925]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "services"... Jul 17 17:22:24 minidsp-shd sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 17:22:24 minidsp-shd sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "alsa_controller"... Jul 17 17:22:24 minidsp-shd sudo[927]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:24 minidsp-shd sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 17:22:24 minidsp-shd sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:24 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "wizard"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "networkfs"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Starting Udev Watcher for removable devices Jul 17 17:22:24 minidsp-shd sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Ivan,password=Cosmic39,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.58.2/music /mnt/NAS/Nas Jul 17 17:22:24 minidsp-shd sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:24 minidsp-shd kernel: FS-Cache: Loaded Jul 17 17:22:24 minidsp-shd volumio[781]: info: Ignoring mount for partition: BOOT Jul 17 17:22:24 minidsp-shd volumio[781]: info: Ignoring mount for partition: volumio Jul 17 17:22:24 minidsp-shd volumio[781]: info: Ignoring mount for partition: volumio_data Jul 17 17:22:24 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "volumio_command_line_client"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "upnp"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: [1752769344726] Starting Upmpd Daemon Jul 17 17:22:24 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "my_music"... Jul 17 17:22:24 minidsp-shd volumio[781]: info: Loading plugin "mpd"... Jul 17 17:22:24 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Jul 17 17:22:24 minidsp-shd kernel: Key type cifs.spnego registered Jul 17 17:22:24 minidsp-shd kernel: Key type cifs.idmap registered Jul 17 17:22:24 minidsp-shd kernel: CIFS: Attempting to mount //192.168.58.2/music Jul 17 17:22:24 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. Jul 17 17:22:25 minidsp-shd sudo[936]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:25 minidsp-shd volumio[781]: info: Loading plugin "upnp_browser"... Jul 17 17:22:26 minidsp-shd volumio[781]: info: Loading plugin "bluetooth"... Jul 17 17:22:26 minidsp-shd volumio[781]: info: [1752769346765] Starting BluetoothController Jul 17 17:22:27 minidsp-shd volumio[781]: info: Loading plugin "alarm-clock"... Jul 17 17:22:27 minidsp-shd volumio[781]: info: Loading plugin "manifestui"... Jul 17 17:22:27 minidsp-shd volumio[781]: info: Loading plugin "metavolumio"... Jul 17 17:22:28 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Jul 17 17:22:28 minidsp-shd sudo[930]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:28 minidsp-shd sh[380]: timed out Jul 17 17:22:28 minidsp-shd sh[380]: dhcpcd exited Jul 17 17:22:28 minidsp-shd dhcpcd[412]: timed out Jul 17 17:22:28 minidsp-shd sh[380]: ifup: failed to bring up eth0 Jul 17 17:22:28 minidsp-shd dhcpcd[412]: dhcpcd exited Jul 17 17:22:28 minidsp-shd systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 17 17:22:28 minidsp-shd systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 17 17:22:30 minidsp-shd volumio[781]: info: Loading plugin "airplay_emulation"... Jul 17 17:22:30 minidsp-shd volumio[781]: info: Starting Shairport Sync Jul 17 17:22:30 minidsp-shd volumio[781]: info: Loading plugin "cd_controller"... Jul 17 17:22:30 minidsp-shd volumio[781]: info: Loading plugin "last_100"... Jul 17 17:22:30 minidsp-shd volumio[781]: info: Loading plugin "raat"... Jul 17 17:22:31 minidsp-shd volumio[781]: info: RAAT Plugin loaded Jul 17 17:22:31 minidsp-shd volumio[781]: info: Adding restartRAATSocket REST API Endpoint Jul 17 17:22:31 minidsp-shd volumio[781]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 17 17:22:31 minidsp-shd volumio[781]: info: Loading plugin "streaming_services"... Jul 17 17:22:33 minidsp-shd volumio[781]: info: Starting Streaming Service Transparent Proxy Jul 17 17:22:33 minidsp-shd volumio[781]: info: Loading plugin "tidalconnect"... Jul 17 17:22:34 minidsp-shd volumio[781]: info: Loading plugin "webradio"... Jul 17 17:22:34 minidsp-shd volumio[781]: info: Loading plugin "i2s_dacs"... Jul 17 17:22:34 minidsp-shd volumio[781]: info: I2S DAC not set, start Auto-detection Jul 17 17:22:34 minidsp-shd volumio[781]: info: Loading plugin "volumiodiscovery"... Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** For more information see Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 17:22:34 minidsp-shd volumio[781]: *** WARNING *** For more information see Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** For more information see Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 17:22:34 minidsp-shd node[781]: *** WARNING *** For more information see Jul 17 17:22:34 minidsp-shd volumio[781]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 17:22:34 minidsp-shd volumio[781]: info: Discovery: Started advertising with name: miniDSP SHD Jul 17 17:22:34 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 17:22:34 minidsp-shd volumio[781]: info: Loading plugin "spop"... Jul 17 17:22:35 minidsp-shd volumio[781]: STREAMING PROXY: Starting server on port 3245 Jul 17 17:22:35 minidsp-shd volumio[781]: Node JS runtime: 14 Jul 17 17:22:37 minidsp-shd volumio[781]: info: Loading plugin "multiroom"... Jul 17 17:22:37 minidsp-shd volumio-remote-updater[482]: [2025-07-17 17:22:37] [connect] Successful connection Jul 17 17:22:40 minidsp-shd volumio[781]: info: Applying required configuration parameters for plugin multiroom Jul 17 17:22:40 minidsp-shd sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 17 17:22:40 minidsp-shd sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:40 minidsp-shd sudo[975]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:40 minidsp-shd volumio[781]: info: MRS: MultiRoom plugin initialized Jul 17 17:22:40 minidsp-shd volumio[781]: info: MRS: STOPPING SNAPCLIENT Jul 17 17:22:40 minidsp-shd volumio[781]: info: MRS: Snap server stop Jul 17 17:22:40 minidsp-shd sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 17 17:22:40 minidsp-shd sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:40 minidsp-shd volumio[781]: info: MRS: STOPPING volumioStreaming Jul 17 17:22:40 minidsp-shd sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 17 17:22:40 minidsp-shd sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:40 minidsp-shd volumio[781]: info: Loading plugin "outputs"... Jul 17 17:22:40 minidsp-shd sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 17 17:22:40 minidsp-shd sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:40 minidsp-shd sudo[991]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:40 minidsp-shd volumio[781]: info: Loading plugin "albumart"... Jul 17 17:22:40 minidsp-shd sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 17 17:22:40 minidsp-shd sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:40 minidsp-shd sudo[999]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:40 minidsp-shd sudo[993]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:40 minidsp-shd volumio[781]: info: Loading plugin "ms_surface_dial"... Jul 17 17:22:40 minidsp-shd sudo[996]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:41 minidsp-shd volumio[781]: Forking 3 albumart workers Jul 17 17:22:42 minidsp-shd volumio[781]: info: Applying required configuration parameters for plugin ms_surface_dial Jul 17 17:22:42 minidsp-shd volumio[781]: info: Plugin example_plugin is not enabled Jul 17 17:22:42 minidsp-shd volumio[781]: info: Loading plugin "hi_res_audio"... Jul 17 17:22:43 minidsp-shd volumio[781]: Starting albumart workers Jul 17 17:22:43 minidsp-shd volumio[781]: Starting albumart workers Jul 17 17:22:43 minidsp-shd volumio[781]: Starting albumart workers Jul 17 17:22:45 minidsp-shd volumio[781]: info: Applying required configuration parameters for plugin hi_res_audio Jul 17 17:22:45 minidsp-shd volumio[781]: info: Loading plugin "inputs"... Jul 17 17:22:46 minidsp-shd volumio[781]: info: Loading plugin "qobuz"... Jul 17 17:22:49 minidsp-shd volumio[781]: info: Loading plugin "tidal"... Jul 17 17:22:51 minidsp-shd volumio[781]: info: Loading plugin "oem_helper"... Jul 17 17:22:52 minidsp-shd volumio-remote-updater[482]: [2025-07-17 17:22:52] [connect] Successful connection Jul 17 17:22:53 minidsp-shd volumio[781]: info: Applying required configuration parameters for plugin oem_helper Jul 17 17:22:53 minidsp-shd volumio[781]: info: Loading plugin "updater_comm"... Jul 17 17:22:53 minidsp-shd volumio[781]: info: Plugin mpdemulation is not enabled Jul 17 17:22:53 minidsp-shd volumio[781]: info: Loading plugin "rest_api"... Jul 17 17:22:53 minidsp-shd volumio[781]: info: Loading plugin "websocket"... Jul 17 17:22:54 minidsp-shd volumio[781]: info: Loading i18n strings for locale en Jul 17 17:22:54 minidsp-shd volumio[781]: Updating browse sources language Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::initPlayerControls Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:22:54 minidsp-shd volumio[781]: Express server listening on port 3000 Jul 17 17:22:54 minidsp-shd volumio[781]: [Metrics] WebUI: 37s 258.49ms Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreStateMachine::resetVolumioState Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreStateMachine::getcurrentVolume Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:22:54 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:54 minidsp-shd sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 17:22:54 minidsp-shd sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Jul 17 17:22:54 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Jul 17 17:22:54 minidsp-shd sudo[1065]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:54 minidsp-shd sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 17:22:54 minidsp-shd sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:54 minidsp-shd sudo[1067]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:54 minidsp-shd volumio[781]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 17 17:22:54 minidsp-shd volumio[781]: info: Completed loading Core Plugins Jul 17 17:22:54 minidsp-shd volumio[781]: info: Preparing to generate the ALSA configuration file Jul 17 17:22:55 minidsp-shd volumio[781]: info: MRS: Removed streaming files Jul 17 17:22:55 minidsp-shd volumio[781]: info: MRS: volumioStreaming STOPPED Jul 17 17:22:55 minidsp-shd volumio[781]: info: MRS: SNAPSERVER STOPPED Jul 17 17:22:55 minidsp-shd volumio[781]: info: MRS: SNAPCLIENT STOPPED Jul 17 17:22:55 minidsp-shd volumio[781]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 17 17:22:55 minidsp-shd volumio[781]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 17 17:22:55 minidsp-shd volumio[781]: info: Reading ALSA contributions from plugins. Jul 17 17:22:55 minidsp-shd volumio-remote-updater[482]: [2025-07-17 17:22:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752769372 101 Jul 17 17:22:55 minidsp-shd volumio[781]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 17 17:22:55 minidsp-shd volumio[781]: info: Setting Device type: NanoPi NEO 3 Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreStateMachine::setRepeat null single undefined Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:22:55 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreStateMachine::setRandom null Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:22:55 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:22:55 minidsp-shd volumio[781]: info: Asound.conf file unchanged, so no further update is needed Jul 17 17:22:55 minidsp-shd volumio[781]: info: Output device has changed, restarting MPD Jul 17 17:22:55 minidsp-shd sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 17 17:22:55 minidsp-shd sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:55 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Jul 17 17:22:55 minidsp-shd sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:22:55 minidsp-shd sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:55 minidsp-shd volumio[781]: info: Output device has changed, restarting Shairport Sync Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:55 minidsp-shd sudo[1079]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:55 minidsp-shd sudo[1076]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:55 minidsp-shd sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:22:55 minidsp-shd sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:55 minidsp-shd systemd[1]: Listening on mpd.socket. Jul 17 17:22:55 minidsp-shd volumio[781]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:22:55 minidsp-shd volumio[781]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 17 17:22:55 minidsp-shd systemd[1]: Starting Music Player Daemon... Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:55 minidsp-shd volumio[781]: info: ___________ START PLUGINS ___________ Jul 17 17:22:55 minidsp-shd volumio[781]: info: ControllerMpd::onStart: Initializing MPD Jul 17 17:22:55 minidsp-shd volumio[781]: info: Creating MPD Configuration file Jul 17 17:22:55 minidsp-shd sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:22:55 minidsp-shd sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:22:55 minidsp-shd volumio[781]: info: [1752769375965] CoreMusicLibrary::Adding element Media Servers Jul 17 17:22:55 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:55 minidsp-shd sudo[1092]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:56 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:22:56 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 17:22:56 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 17 17:22:56 minidsp-shd systemd[1]: mpd.service: Succeeded. Jul 17 17:22:56 minidsp-shd systemd[1]: Stopped Music Player Daemon. Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding Manifest REST API Endpoints Jul 17 17:22:56 minidsp-shd systemd[1]: Starting Music Player Daemon... Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding METAVOLUMIO REST API Endpoints Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:56 minidsp-shd sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Jul 17 17:22:56 minidsp-shd sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:56 minidsp-shd sudo[1098]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:56 minidsp-shd volumio[781]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:56 minidsp-shd volumio[781]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:22:56 minidsp-shd volumio[781]: info: Preparing CD Folders Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding CD REST API Endpoints Jul 17 17:22:56 minidsp-shd volumio[781]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 17 17:22:56 minidsp-shd volumio[781]: info: Starting UDEV Watcher for CD Jul 17 17:22:56 minidsp-shd volumio[781]: info: Detecting CD presence with UDEV Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:22:56 minidsp-shd volumio[781]: info: [1752769376382] CoreMusicLibrary::Adding element Last_100 Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:56 minidsp-shd volumio[781]: info: Starting RAAT Plugin Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 17 17:22:56 minidsp-shd volumio[781]: info: Additional UI Settings Added for plugin music_service/raat Jul 17 17:22:56 minidsp-shd volumio[781]: info: Streaming services startup Jul 17 17:22:56 minidsp-shd volumio[781]: info: Starting Streaming Daemon Jul 17 17:22:56 minidsp-shd sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 17 17:22:56 minidsp-shd sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:56 minidsp-shd sudo[1110]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:22:56 minidsp-shd volumio[781]: info: [1752769376639] CoreMusicLibrary::Adding element Webradio Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 17:22:56 minidsp-shd sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 17 17:22:56 minidsp-shd sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:56 minidsp-shd volumio[781]: info: Creating Spotify config file Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:56 minidsp-shd sudo[1117]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 17 17:22:56 minidsp-shd volumio[781]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 17 17:22:56 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:57 minidsp-shd dbus-daemon[463]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=781 comm="/usr/bin/node /volumio/index.js ") Jul 17 17:22:57 minidsp-shd systemd[1]: Starting Bluetooth service... Jul 17 17:22:57 minidsp-shd volumio[781]: error: Hi Res Audio Failed Login: Missing Login Data Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 17 17:22:57 minidsp-shd bluetoothd[1123]: Bluetooth daemon 5.23 Jul 17 17:22:57 minidsp-shd dbus-daemon[463]: [system] Successfully activated service 'org.bluez' Jul 17 17:22:57 minidsp-shd systemd[1]: Started Bluetooth service. Jul 17 17:22:57 minidsp-shd bluetoothd[1123]: Starting SDP server Jul 17 17:22:57 minidsp-shd bluetoothd[1123]: kernel lacks bnep-protocol support Jul 17 17:22:57 minidsp-shd bluetoothd[1123]: System does not support network plugin Jul 17 17:22:57 minidsp-shd dbus-daemon[463]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1123 comm="/usr/local/libexec/bluetooth/bluetoothd ") Jul 17 17:22:57 minidsp-shd bluetoothd[1123]: Bluetooth management interface 1.14 initialized Jul 17 17:22:57 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 17:22:57 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:57 minidsp-shd volumio[781]: info: Volumio Calling Home Jul 17 17:22:57 minidsp-shd systemd[1]: Starting Hostname Service... Jul 17 17:22:57 minidsp-shd volumio[781]: info: Stopping AccessToken refresher cron for QOBUZ Jul 17 17:22:57 minidsp-shd dbus-daemon[463]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 17 17:22:57 minidsp-shd systemd[1]: Started Hostname Service. Jul 17 17:22:57 minidsp-shd volumio[781]: info: AccessToken refresher cron started for QOBUZ Jul 17 17:22:57 minidsp-shd volumio[781]: info: Stopping AccessToken refresher cron Jul 17 17:22:57 minidsp-shd volumio[781]: info: AccessToken refresher cron started Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding TIDAL REST API Endpoints Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding QOBUZ REST API Endpoints Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 17 17:22:57 minidsp-shd volumio[781]: info: Discovery: adding 777a4826-32e1-4510-9b1c-e886c6913657 Jul 17 17:22:57 minidsp-shd volumio[781]: info: Discovery: Found device miniDSP SHD Jul 17 17:22:57 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioGetState Jul 17 17:22:57 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:22:57 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output for this device Jul 17 17:22:57 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:57 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:57 minidsp-shd volumio[781]: info: Serial port opened successfully Jul 17 17:22:58 minidsp-shd volumio[781]: error: Cannot start Volumio Streaming Daemon Jul 17 17:22:58 minidsp-shd volumio[781]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 17 17:22:58 minidsp-shd volumio[781]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 17 17:22:58 minidsp-shd volumio[781]: info: RAAT Albumart path created successfully Jul 17 17:22:58 minidsp-shd volumio[781]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Jul 17 17:22:58 minidsp-shd volumio[781]: Can't get device info: No such device Jul 17 17:22:58 minidsp-shd volumio[781]: info: MPD Permissions set Jul 17 17:22:58 minidsp-shd volumio[781]: info: MPD Permissions set Jul 17 17:22:58 minidsp-shd volumio[781]: info: Upmpdcli Daemon Started Jul 17 17:22:58 minidsp-shd volumio[781]: info: msSurfaceDial volumioupdatevolume callback: {"vol":67,"dbVolume":-42.5,"disableVolumeControl":false} Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:22:58 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:22:58 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:22:58 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jul 17 17:22:58 minidsp-shd volumio[781]: info: Ignoring ROON Volume update because of undefined data Jul 17 17:22:58 minidsp-shd volumio[781]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Jul 17 17:22:58 minidsp-shd volumio[781]: info: [msSurfaceDial init()] check /org/bluez Jul 17 17:22:58 minidsp-shd volumio[781]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Jul 17 17:22:58 minidsp-shd volumio[781]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::Reload Ui Jul 17 17:22:58 minidsp-shd volumio[781]: info: Volumio called home Jul 17 17:22:58 minidsp-shd volumio[781]: info: Spotify config file written Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jul 17 17:22:58 minidsp-shd volumio[781]: info: updateDSP function in raat called! Jul 17 17:22:58 minidsp-shd volumio[781]: info: Updating RAAT Signal Path Jul 17 17:22:58 minidsp-shd volumio[781]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Jul 17 17:22:58 minidsp-shd volumio[781]: info: Reconfiguring and Restarting RAAT Plugin Jul 17 17:22:58 minidsp-shd sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 17 17:22:58 minidsp-shd sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:58 minidsp-shd systemd[1]: Started go-librespot Daemon. Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:22:58 minidsp-shd go-librespot[1146]: go-librespot daemon starting... Jul 17 17:22:58 minidsp-shd volumio[781]: info: Not Reporting Auto name since its the default one Jul 17 17:22:58 minidsp-shd volumio[781]: info: RAAT Overriding default device vendor model Jul 17 17:22:58 minidsp-shd volumio[781]: info: Adding MINIDSP Inputs Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:22:58 minidsp-shd volumio[781]: info: [1752769378643] CoreMusicLibrary::Adding element Inputs Jul 17 17:22:58 minidsp-shd sudo[1140]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:22:58 minidsp-shd volumio[781]: info: [1752769378655] CoreMusicLibrary::Adding element Presets Jul 17 17:22:58 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:22:58 minidsp-shd volumio[781]: Cannot find translation for source Presets Jul 17 17:22:58 minidsp-shd volumio[781]: (node:781) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 17 17:22:58 minidsp-shd volumio[781]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 17 17:22:58 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:58 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:58+01:00" level=info msg="running go-librespot 0.2.0" Jul 17 17:22:58 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:58+01:00" level=debug msg="app state loaded" Jul 17 17:22:58 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:22:59 minidsp-shd volumio[781]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 17 17:22:59 minidsp-shd sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:22:59 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Jul 17 17:22:59 minidsp-shd sudo[1155]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd volumiobt[1161]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 17 17:22:59 minidsp-shd sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:22:59 minidsp-shd sudo[1164]: pam_unix(sudo:session): session closed for user root Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:22:59 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:22:59 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 17 17:22:59 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:59+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 17 17:22:59 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:59+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 17 17:22:59 minidsp-shd volumio[781]: info: No need to fix Spotify hosts Jul 17 17:22:59 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:59+01:00" level=info msg="zeroconf server listening on port 35177" Jul 17 17:22:59 minidsp-shd go-librespot[1146]: time="2025-07-17T17:22:59+01:00" level=debug msg="obtained new client token: AAD7Q4ED015zMP8YsQBqJwhHf9BYilFkZKpW5KRm8i/iaDfQvf9OpnaFzFCfeiz3pqNAeypGi5Wax4qCP6ZNdKpIy6+wNBI9qeFXVHVrrpiuHEOHNiPmj40JnUUMFNTCv9CLE7uta6pYPbKi8nqizG7cE71bzL0+5jLPYeFiuiRZgINajvJEpmr7am2qDkY37xunSHjxnTWrCGsWUyZh0eMldn5RFi4vlleo8OzM3g+vwJbdXpNFmdif" Jul 17 17:23:00 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:00 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:00 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:00 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:00 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="completed keyexchange" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="completed challenge" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=info msg="authenticated AP as ivansalfa" Jul 17 17:23:00 minidsp-shd sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 17 17:23:00 minidsp-shd sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:00 minidsp-shd volumiobt[1161]: pulseaudio: no process found Jul 17 17:23:00 minidsp-shd sudo[1209]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=info msg="authenticated Login5 as ivansalfa" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="initializing zeroconf session, username: ivansalfa" Jul 17 17:23:00 minidsp-shd dbus-daemon[463]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.14' (uid=1000 pid=1214 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="dealer connection opened" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=trace msg="starting accesspoint recv loop" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=trace msg="starting dealer recv loop" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=trace msg="received accesspoint ping" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 17 17:23:00 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:00+01:00" level=debug msg="received connection id: ZjM5ZjU4OTQtMmRjMC00YTM1LTgxZmYtMmRjNzljN2Y4YTVjK2RlYWxlcit0Y3A6Ly8wYWNhNDIzYS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArNjk3REQ2QUQ1NTVENTMwQTMzRDhBQUEyRUZEOTZENDM4NThGRUVGREQyMjZFQjI3Qzk0MUEzQjg2ODU3REZBOQ==" Jul 17 17:23:01 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 17 17:23:01 minidsp-shd dbus-daemon[463]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 17 17:23:01 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Successfully called chroot. Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Successfully dropped privileges. Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Successfully limited resources. Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Running. Jul 17 17:23:01 minidsp-shd dbus-daemon[463]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.15' (uid=0 pid=1215 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Watchdog thread running. Jul 17 17:23:01 minidsp-shd rtkit-daemon[1215]: Canary thread running. Jul 17 17:23:01 minidsp-shd systemd[1]: Starting Authorization Manager... Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=debug msg="put connect state because NEW_DEVICE" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=debug msg="update volume to 65535/65535" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 17 17:23:01 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:01+01:00" level=trace msg="received accesspoint pong ack" Jul 17 17:23:01 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 17:23:01 minidsp-shd volumio[781]: info: Enabling external Volume Control Jul 17 17:23:01 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:01 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:01 minidsp-shd polkitd[1218]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 17 17:23:01 minidsp-shd dbus-daemon[463]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 17 17:23:01 minidsp-shd systemd[1]: Started Authorization Manager. Jul 17 17:23:01 minidsp-shd volumio[781]: info: TidalConnect service stoped! Jul 17 17:23:01 minidsp-shd volumio[781]: info: Volumio BT Module successfully started Jul 17 17:23:01 minidsp-shd volumio[781]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 17 17:23:01 minidsp-shd volumio[781]: SPOTIFY: BQA-mopYPgEOdwMczDoCE8Pgx60ACk0SFETVoV4G4cNJCR7Fkby9P6Ag8f1tKeDhJPk2YPdpeCZldkPy7dtMGw4tw0Cy7TsKNy4QoEcye1tQjhxUtLAHx3EyQr3rMZgxB3C-zA097Pz_oEbliz0HpihItSQPncwhtqztHWpE75VwNHjvsnLT0WgFjhyuFURJlSp1nN7PhT8nKEsxWZkR1greFQZZXb-T5DsG62Pa-WHxoPE3S2ueX86Q25dyfwdasOxYRaTdXXB2NbMIGGzezJJ8gQ Jul 17 17:23:01 minidsp-shd volumio[781]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 17 17:23:01 minidsp-shd volumio[781]: info: New Spotify access token = BQA-mopYPgEOdwMczDoCE8Pgx60ACk0SFETVoV4G4cNJCR7Fkby9P6Ag8f1tKeDhJPk2YPdpeCZldkPy7dtMGw4tw0Cy7TsKNy4QoEcye1tQjhxUtLAHx3EyQr3rMZgxB3C-zA097Pz_oEbliz0HpihItSQPncwhtqztHWpE75VwNHjvsnLT0WgFjhyuFURJlSp1nN7PhT8nKEsxWZkR1greFQZZXb-T5DsG62Pa-WHxoPE3S2ueX86Q25dyfwdasOxYRaTdXXB2NbMIGGzezJJ8gQ Jul 17 17:23:01 minidsp-shd volumio[781]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 17 17:23:01 minidsp-shd volumio[781]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 17 17:23:01 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:23:01 minidsp-shd volumio[781]: Cannot find translation for source Presets Jul 17 17:23:01 minidsp-shd volumio[781]: info: Starting Shairport Sync Jul 17 17:23:01 minidsp-shd volumio[781]: info: Starting Shairport Sync Jul 17 17:23:02 minidsp-shd volumio[781]: info: Starting Shairport Sync Jul 17 17:23:02 minidsp-shd sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:23:02 minidsp-shd sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:02 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 17 17:23:02 minidsp-shd sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:23:02 minidsp-shd sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:02 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:23:02 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:23:02 minidsp-shd sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:23:02 minidsp-shd sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:02 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:23:02 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 17 17:23:02 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:02+01:00" level=trace msg="emitting websocket event: volume" Jul 17 17:23:02 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:23:02 minidsp-shd volumio[781]: xcb_connection_has_error() returned true Jul 17 17:23:02 minidsp-shd sudo[1236]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:02 minidsp-shd sudo[1234]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:02 minidsp-shd sudo[1231]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:02 minidsp-shd pulseaudio[1214]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 17 17:23:02 minidsp-shd pulseaudio[1214]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 17 17:23:02 minidsp-shd volumiobt[1161]: Applying permissions Jul 17 17:23:02 minidsp-shd volumiobt[1161]: Setting BT discoverable and pairable Jul 17 17:23:02 minidsp-shd sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 17 17:23:02 minidsp-shd sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:02 minidsp-shd pulseaudio[1214]: 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 Jul 17 17:23:02 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 17 17:23:02 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 17:23:02 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 17:23:02 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [38B blob data] Jul 17 17:23:02 minidsp-shd volumiobt[1161]: Invalid command Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# agent on Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# discoverable on Jul 17 17:23:02 minidsp-shd volumiobt[1161]: No default controller available Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# pairable on Jul 17 17:23:02 minidsp-shd volumiobt[1161]: No default controller available Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# agent NoInputNoOutput Jul 17 17:23:02 minidsp-shd volumiobt[1161]: Failed to register agent object Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# default-agent Jul 17 17:23:02 minidsp-shd volumiobt[1161]: No agent is registered Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [bluetooth]# quit Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [61B blob data] Jul 17 17:23:02 minidsp-shd volumiobt[1161]: [62B blob data] Jul 17 17:23:02 minidsp-shd systemd[1]: Started RAAT DAEMON. Jul 17 17:23:02 minidsp-shd sudo[1244]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:02 minidsp-shd volumio[781]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 17 17:23:02 minidsp-shd volumio[781]: info: MRS: Getting audio outputs on start Jul 17 17:23:02 minidsp-shd volumio[781]: info: MRS: Requesting all other devices output Jul 17 17:23:03 minidsp-shd volumio[781]: info: Raat Daemon started successfully Jul 17 17:23:03 minidsp-shd volumio[781]: info: Shairport-Sync Started Jul 17 17:23:03 minidsp-shd volumio[781]: Error adding Membership: Error: addMembership EINVAL Jul 17 17:23:03 minidsp-shd volumio[781]: info: Shairport-Sync Started Jul 17 17:23:03 minidsp-shd volumio[781]: info: Shairport-Sync Started Jul 17 17:23:03 minidsp-shd volumio[781]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 17 17:23:03 minidsp-shd volumio[781]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 17 17:23:03 minidsp-shd volumio[781]: info: MRS: Found cast device: LG-SN-Series-ThinQ-S-7aae5777b2965f7669d2d379e018c393 Jul 17 17:23:03 minidsp-shd volumio[781]: info: Adding audio output: Jul 17 17:23:03 minidsp-shd volumio[781]: info: msSurfaceDial volumioupdatevolume callback: {"vol":67,"dbVolume":-42.5,"mute":false,"disableVolumeControl":false} Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:03 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:03 minidsp-shd sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 17 17:23:03 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:03 minidsp-shd sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:03 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:03 minidsp-shd volumio[781]: error: Help! Some callbacks for volumioPushState are crashing! Jul 17 17:23:03 minidsp-shd volumio[781]: error: Cannot read property 'sendVolumeMute' of undefined Jul 17 17:23:03 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jul 17 17:23:03 minidsp-shd sudo[1262]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:03 minidsp-shd volumio[781]: info: Executing endpoint restartRAATSocket Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioGetState Jul 17 17:23:03 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:03 minidsp-shd volumio[781]: info: msSurfaceDial volumioupdatevolume callback: {"vol":67,"dbVolume":-42.5,"mute":false,"disableVolumeControl":false} Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:03 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:03 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:03 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:03 minidsp-shd volumio[781]: error: Help! Some callbacks for volumioPushState are crashing! Jul 17 17:23:03 minidsp-shd volumio[781]: error: Cannot read property 'sendVolumeMute' of undefined Jul 17 17:23:03 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jul 17 17:23:04 minidsp-shd volumio[781]: info: go-librespot daemon successfully initialized Jul 17 17:23:04 minidsp-shd volumio[781]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jul 17 17:23:04 minidsp-shd volumio[781]: info: Executing endpoint tc_getconfig Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 17 17:23:04 minidsp-shd vtcs[1264]: STARTING TidalConnect services, version: 1.3.0.19 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioGetState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: SPOTIFY: User informations: {"country":"GB","display_name":"Ivan Jervis","email":"g1ygd@btinternet.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ivansalfa"},"followers":{"href":null,"total":5},"href":"https://api.spotify.com/v1/users/ivansalfa","id":"ivansalfa","images":[{"height":300,"url":"https://scontent-ord5-2.xx.fbcdn.net/v/t39.30808-1/465706143_10235364353714228_8085752415373526494_n.jpg?stp=c255.0.1365.1365a_dst-jpg_s320x320_tt6&_nc_cat=105&ccb=1-7&_nc_sid=79bf43&_nc_ohc=Jm-G9qCUhn8Q7kNvwH77oKy&_nc_oc=AdkpQqpdScUj3rA14rrJslJb_hR9Tzuoxw8mGeMSiIt688_9iQgQW2YduUKmI4tAsD70il6d7-RMLn9KHJVRmlwc&_nc_zt=24&_nc_ht=scontent-ord5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=tzVRarubIxbMFUvAuLXi8A&oh=00_AfQOZMDWp0ZFeDiDICgPsXHUiCcSp-WndEKfPE-GavXaVA&oe=687EB8F3","width":300},{"height":64,"url":"https://scontent-ord5-2.xx.fbcdn.net/v/t39.30808-1/465706143_10235364353714228_8085752415373526494_n.jpg?stp=c255.0.1365.1365a_cp0_dst-jpg_s50x50_tt6&_nc_cat=105&ccb=1-7&_nc_sid=28885b&_nc_ohc=Jm-G9qCUhn8Q7kNvwH77oKy&_nc_oc=AdkpQqpdScUj3rA14rrJslJb_hR9Tzuoxw8mGeMSiIt688_9iQgQW2YduUKmI4tAsD70il6d7-RMLn9KHJVRmlwc&_nc_zt=24&_nc_ht=scontent-ord5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=tzVRarubIxbMFUvAuLXi8A&oh=00_AfQE0v0uS_Hor6_BvqUZR_-AuFiarh_EHaeNLDgUavpCEg&oe=687EB8F3","width":64}],"product":"premium","type":"user","uri":"spotify:user:ivansalfa"} Jul 17 17:23:04 minidsp-shd volumio[781]: info: Spotify Successfully logged in Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 17:23:04 minidsp-shd volumio[781]: info: [1752769384619] CoreMusicLibrary::Adding element Spotify Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:23:04 minidsp-shd volumio[781]: Cannot find translation for source Presets Jul 17 17:23:04 minidsp-shd volumio[781]: Cannot find translation for source Spotify Jul 17 17:23:04 minidsp-shd vtcs[1264]: STARTED TidalConnect services. Jul 17 17:23:04 minidsp-shd volumio[781]: info: Executing endpoint tc_connect Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 17 17:23:04 minidsp-shd volumio[781]: info: Connecting to TidalConnect Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::servicePushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: 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} Jul 17 17:23:04 minidsp-shd volumio[781]: verbose: CURRENT POSITION 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::syncState stateService stop Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::syncState currentStatus stop Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:04 minidsp-shd volumio[781]: info: No code Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::servicePushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: 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} Jul 17 17:23:04 minidsp-shd volumio[781]: verbose: CURRENT POSITION 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::syncState stateService stop Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::syncState currentStatus stop Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:04 minidsp-shd volumio[781]: info: No code Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreStateMachine::pushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:04 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioPushState Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output update for this device Jul 17 17:23:04 minidsp-shd volumio[781]: info: MRS: Pushing multiroomSync output Jul 17 17:23:05 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:05+01:00" level=debug msg="handling transfer player command from 87ec506096e59faeb707e785a9bcc50123fdf0e4" Jul 17 17:23:05 minidsp-shd mpd[1099]: Jul 17 17:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 17:23:05 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:05+01:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:05 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:05+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:05 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:05+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:05 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:05+01:00" level=trace msg="fetched new page 1 with 50 items (list: 100)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd systemd[1]: Started Music Player Daemon. Jul 17 17:23:06 minidsp-shd volumio[781]: Upnp client error: Error: This socket has been ended by the other party Jul 17 17:23:06 minidsp-shd volumio[781]: Upnp client error: Error: This socket has been ended by the other party Jul 17 17:23:06 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:06 minidsp-shd sudo[1083]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=trace msg="fetched new page 2 with 50 items (list: 150)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:23:06 minidsp-shd volumio[781]: info: Not Reporting Auto name since its the default one Jul 17 17:23:06 minidsp-shd volumio[781]: info: RAAT Overriding default device vendor model Jul 17 17:23:06 minidsp-shd volumio[781]: info: MPD running with PID1099 Jul 17 17:23:06 minidsp-shd volumio[781]: ,establishing connection Jul 17 17:23:06 minidsp-shd volumio[781]: info: TidalConnect service started! Jul 17 17:23:06 minidsp-shd volumio[781]: info: Completed starting Core Plugins Jul 17 17:23:06 minidsp-shd volumio[781]: info: ------------------------------------------- Jul 17 17:23:06 minidsp-shd volumio[781]: info: ----- MyVolumio plugins startup ---- Jul 17 17:23:06 minidsp-shd volumio[781]: info: ------------------------------------------- Jul 17 17:23:06 minidsp-shd volumio[781]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 17 17:23:06 minidsp-shd volumio[781]: error: MPD error: The expression evaluated to a falsy value: Jul 17 17:23:06 minidsp-shd volumio[781]: assert.ok(self.idling) Jul 17 17:23:06 minidsp-shd volumio[781]: error: The expression evaluated to a falsy value: Jul 17 17:23:06 minidsp-shd volumio[781]: assert.ok(self.idling) Jul 17 17:23:06 minidsp-shd volumio[781]: error: MPD error: The expression evaluated to a falsy value: Jul 17 17:23:06 minidsp-shd volumio[781]: assert.ok(self.idling) Jul 17 17:23:06 minidsp-shd volumio[781]: error: The expression evaluated to a falsy value: Jul 17 17:23:06 minidsp-shd volumio[781]: assert.ok(self.idling) Jul 17 17:23:06 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 17 17:23:06 minidsp-shd volumio[781]: xcb_connection_has_error() returned true Jul 17 17:23:06 minidsp-shd volumio[781]: error: updateQueue error: null Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=trace msg="fetched new page 3 with 50 items (list: 200)" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 17 17:23:06 minidsp-shd sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=warning msg="failed seeking to track in context spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" error="failed fetching tracks for seek: failed moving to next index 200 (page 4): failed fetching next page: invalid status code from page at radio-apollo/v5/tracks/spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm?salt=326083&autoplay=false&count=50&isVideo=false&prev_tracks=62wcjED7Q0eZLLtyp2Rlwt,2bwSCIuNtVrQPVddCi8sOW,2stkLJ0JNcXkIRDNF3ld6c,2dSzuz0m6CVUYZ0bFykYZI,3JlqQyrjz3MtZk2AK5V57u,3qBvrLYZJyo5MT56Jg6YAX,5k3U0OGYBccHdKJJu3HrUN,6uk7QQiWRDjPHSiSMZlez8,7tUSJY4nsDBJTjd1UXKRsT,0B21ADrX2QX9dPHvdZlRz4,0qcr5FMsEO85NAQjrlDRKo,0J6lmLT94yta2KqsZ86dZv,0qxtQ8rf3W1nId3D2r0xH4,6U4VqEHy4n5VeiH4pQPL24,0ssiA347C7W2OkFInzvnBF,3fzqWttckvSJS7KPGPiWAg,3Z0oQ8r78OUaHvGPiDBR3W,3XoYqtiWHhsk59frZupImG,0SccFtYdj75y6lL2OW10Cf,3ZJnc1eGicPxRitBoC7eWZ,17FFmt5izMIohrN4kCj9gS,6dXkX4y7QWoTkhjAnjRw7h,0l204bJ3SkIgVavJXEhogp,0buWWJU9TGVJUWCkEsHKos,28UMEtwyUUy5u0UWOVHwiI,6FHUBs8P5qcjpj7C2QHdEq,2VeLdiYiILcCOEV0izfzEW,7rzMGJGXXkuw2IbyHqdZlp,7qQDSdhFkh0MMAE1LO5c3K,0Gl5s8IhMmQE5YQwM8Qx1J,5yvHjO06WHe7ETlgirLjbw,29GNq0bPjVrqpISQGwj8Yx,3qrH2YFnbEiQeUe4wzgOuP,0dKpTh3oDHuBTwPlKiCxAF,1iCHl2R7xoEsh7CvYe5Ej0,4b1yxSdlumA8N4fEk4UOZp,4UUvDEDS0ueSw7yhgZQtg1,4Yxc55NX3tAXC2mHRAhtcW,6oYkwjI1TKP9D0Y9II1GT7,2wCRJwiL1WSrW0Dwfco7Nj,68clMtCKOjuXziJhpTfzDg,2d1AiZExnhYoauaSWkB1Fm,0zSxss7OB64DbEyhh5ARCi,2eCAySGeTsPGXnfFb60Kgq,4X25mFLehFnxa1TwtLKcE2,1qrpoAMXodY6895hGKoUpA,3T74kgnbHw8JqkYGJKD4Tl,1gilgPrTkkZTp09Xf8zCmK,630sXRhIcfwr2e4RdNtjKN,6K5ph5mq1qprHae3TrgTj5&minimal=true?salt=346479&autoplay=false&count=50&isVideo=false&prev_tracks=62wcjED7Q0eZLLtyp2Rlwt,2bwSCIuNtVrQPVddCi8sOW,2stkLJ0JNcXkIRDNF3ld6c,0B21ADrX2QX9dPHvdZlRz4,7tUSJY4nsDBJTjd1UXKRsT,5k3U0OGYBccHdKJJu3HrUN,2dSzuz0m6CVUYZ0bFykYZI,6uk7QQiWRDjPHSiSMZlez8,0ssiA347C7W2OkFInzvnBF,3qBvrLYZJyo5MT56Jg6YAX,3fzqWttckvSJS7KPGPiWAg,0qcr5FMsEO85NAQjrlDRKo,3JlqQyrjz3MtZk2AK5V57u,6U4VqEHy4n5VeiH4pQPL24,0qxtQ8rf3W1nId3D2r0xH4,0J6lmLT94yta2KqsZ86dZv,17FFmt5izMIohrN4kCj9gS,0Gl5s8IhMmQE5YQwM8Qx1J,3qrH2YFnbEiQeUe4wzgOuP,3ZJnc1eGicPxRitBoC7eWZ,3Z0oQ8r78OUaHvGPiDBR3W,5yvHjO06WHe7ETlgirLjbw,4b1yxSdlumA8N4fEk4UOZp,0zSxss7OB64DbEyhh5ARCi,6dXkX4y7QWoTkhjAnjRw7h,29GNq0bPjVrqpISQGwj8Yx,0buWWJU9TGVJUWCkEsHKos,0k7Shopj669EbywEjsqoqm,0l204bJ3SkIgVavJXEhogp,4UUvDEDS0ueSw7yhgZQtg1,0dKpTh3oDHuBTwPlKiCxAF,6FHUBs8P5qcjpj7C2QHdEq,7bhfPxNDc3Wtzu5Fx8FnNx,0SccFtYdj75y6lL2OW10Cf,1iCHl2R7xoEsh7CvYe5Ej0,3XoYqtiWHhsk59frZupImG,2VeLdiYiILcCOEV0izfzEW,2wCRJwiL1WSrW0Dwfco7Nj,4dk3oQ3sgaFl6mHa0OO7tE,630sXRhIcfwr2e4RdNtjKN,4Yxc55NX3tAXC2mHRAhtcW,1Tt7zr1yDbKbT8L4jzSZ74,7qQDSdhFkh0MMAE1LO5c3K,3T74kgnbHw8JqkYGJKD4Tl,6K5ph5mq1qprHae3TrgTj5,4X25mFLehFnxa1TwtLKcE2,28UMEtwyUUy5u0UWOVHwiI,1gilgPrTkkZTp09Xf8zCmK,3n1lJB7m8iNDToXJVzIHD2,0e52VJDCa8dSqLttGjw2Hz?salt=800214&autoplay=false&count=50&isVideo=false&prev_tracks=62wcjED7Q0eZLLtyp2Rlwt,2bwSCIuNtVrQPVddCi8sOW,3JlqQyrjz3MtZk2AK5V57u,0B21ADrX2QX9dPHvdZlRz4,2dSzuz0m6CVUYZ0bFykYZI,5k3U0OGYBccHdKJJu3HrUN,7tUSJY4nsDBJTjd1UXKRsT,6uk7QQiWRDjPHSiSMZlez8,3qBvrLYZJyo5MT56Jg6YAX,2stkLJ0JNcXkIRDNF3ld6c,0qcr5FMsEO85NAQjrlDRKo,0ssiA347C7W2OkFInzvnBF,0qxtQ8rf3W1nId3D2r0xH4,6U4VqEHy4n5VeiH4pQPL24,0J6lmLT94yta2KqsZ86dZv,3fzqWttckvSJS7KPGPiWAg,3Z0oQ8r78OUaHvGPiDBR3W,3qrH2YFnbEiQeUe4wzgOuP,3T74kgnbHw8JqkYGJKD4Tl,3ZJnc1eGicPxRitBoC7eWZ,4b1yxSdlumA8N4fEk4UOZp,17FFmt5izMIohrN4kCj9gS,7rzMGJGXXkuw2IbyHqdZlp,28UMEtwyUUy5u0UWOVHwiI,0Gl5s8IhMmQE5YQwM8Qx1J,29GNq0bPjVrqpISQGwj8Yx,0l204bJ3SkIgVavJXEhogp,4Yxc55NX3tAXC2mHRAhtcW,6dXkX4y7QWoTkhjAnjRw7h,1iCHl2R7xoEsh7CvYe5Ej0,0SccFtYdj75y6lL2OW10Cf,6FHUBs8P5qcjpj7C2QHdEq,0k7Shopj669EbywEjsqoqm,0zSxss7OB64DbEyhh5ARCi,1gilgPrTkkZTp09Xf8zCmK,5yvHjO06WHe7ETlgirLjbw,0dKpTh3oDHuBTwPlKiCxAF,0buWWJU9TGVJUWCkEsHKos,3XoYqtiWHhsk59frZupImG,2VeLdiYiILcCOEV0izfzEW,1Tt7zr1yDbKbT8L4jzSZ74,6oYkwjI1TKP9D0Y9II1GT7,2wCRJwiL1WSrW0Dwfco7Nj,4UUvDEDS0ueSw7yhgZQtg1,630sXRhIcfwr2e4RdNtjKN,7bhfPxNDc3Wtzu5Fx8FnNx,61HVbcNeRACZpyvHrc3AnD,7qQDSdhFkh0MMAE1LO5c3K,1qrpoAMXodY6895hGKoUpA,4dk3oQ3sgaFl6mHa0OO7tE?salt=173598&autoplay=false&count=50&isVideo=false&prev_tracks=62wcjED7Q0eZLLtyp2Rlwt,2bwSCIuNtVrQPVddCi8sOW,0B21ADrX2QX9dPHvdZlRz4,7tUSJY4nsDBJTjd1UXKRsT,3qBvrLYZJyo5MT56Jg6YAX,3JlqQyrjz3MtZk2AK5V57u,0ssiA347C7W2OkFInzvnBF,5k3U0OGYBccHdKJJu3HrUN,3fzqWttckvSJS7KPGPiWAg,2stkLJ0JNcXkIRDNF3ld6c,3ZJnc1eGicPxRitBoC7eWZ,0J6lmLT94yta2KqsZ86dZv,0qcr5FMsEO85NAQjrlDRKo,0qxtQ8rf3W1nId3D2r0xH4,2dSzuz0m6CVUYZ0bFykYZI,7rzMGJGXXkuw2IbyHqdZlp,6uk7QQiWRDjPHSiSMZlez8,0Gl5s8IhMmQE5YQwM8Qx1J,3XoYqtiWHhsk59frZupImG,5yvHjO06WHe7ETlgirLjbw,0buWWJU9TGVJUWCkEsHKos,2VeLdiYiILcCOEV0izfzEW,6U4VqEHy4n5VeiH4pQPL24,3Z0oQ8r78OUaHvGPiDBR3W,4b1yxSdlumA8N4fEk4UOZp,0SccFtYdj75y6lL2OW10Cf,6dXkX4y7QWoTkhjAnjRw7h,7bhfPxNDc3Wtzu5Fx8FnNx,29GNq0bPjVrqpISQGwj8Yx,3qrH2YFnbEiQeUe4wzgOuP,7qQDSdhFkh0MMAE1LO5c3K,3T74kgnbHw8JqkYGJKD4Tl,28UMEtwyUUy5u0UWOVHwiI,0zSxss7OB64DbEyhh5ARCi,2wCRJwiL1WSrW0Dwfco7Nj,0l204bJ3SkIgVavJXEhogp,0k7Shopj669EbywEjsqoqm,1qrpoAMXodY6895hGKoUpA,1gilgPrTkkZTp09Xf8zCmK,4UUvDEDS0ueSw7yhgZQtg1,6FHUBs8P5qcjpj7C2QHdEq,20QxeN0YcZZiFW8wM6XUck,630sXRhIcfwr2e4RdNtjKN,1iCHl2R7xoEsh7CvYe5Ej0,6oYkwjI1TKP9D0Y9II1GT7,4dk3oQ3sgaFl6mHa0OO7tE,68clMtCKOjuXziJhpTfzDg,2eCAySGeTsPGXnfFb60Kgq,17FFmt5izMIohrN4kCj9gS,1fTDYdx5zYfWJSX0JlKO8o: 414" uri="spotify:station:playlist:1EgQ5SADn2DCd9TDh24Mtm" Jul 17 17:23:06 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:06+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:06 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jul 17 17:23:06 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 17 17:23:06 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jul 17 17:23:06 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jul 17 17:23:06 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 17 17:23:06 minidsp-shd volumio[781]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 17 17:23:06 minidsp-shd systemd[1]: Started RAAT DAEMON. Jul 17 17:23:06 minidsp-shd sudo[1296]: pam_unix(sudo:session): session closed for user root Jul 17 17:23:06 minidsp-shd volumio[781]: info: Raat Daemon started successfully Jul 17 17:23:07 minidsp-shd volumio[1081]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=trace msg="emitting websocket event: will_play" Jul 17 17:23:07 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jul 17 17:23:07 minidsp-shd volumio[781]: info: updateDSP function in raat called! Jul 17 17:23:07 minidsp-shd volumio[781]: info: Updating RAAT Signal Path Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="selected format OGG_VORBIS_320 (b4d5e22c5e145843d4cc68015b1662a651b9db42)" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="requested aes key for file b4d5e22c5e145843d4cc68015b1662a651b9db42, gid: 62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3256" Jul 17 17:23:07 minidsp-shd volumio[781]: info: Executing endpoint restartRAATSocket Jul 17 17:23:07 minidsp-shd volumio[781]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 17 17:23:07 minidsp-shd volumio[781]: info: Initializing connection to go-librespot Websocket Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="new websocket client" Jul 17 17:23:07 minidsp-shd volumio[781]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jul 17 17:23:07 minidsp-shd volumio[781]: info: Connection to go-librespot Websocket established Jul 17 17:23:07 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioGetState Jul 17 17:23:07 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2074" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="fetched first chunk of 14, total size is 6842872 bytes" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="created new output device" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=info msg="loaded track \"How Far I'll Go - From \\\"Moana\\\"/Soundtrack Version\" (paused: true, position: 0ms, duration: 163386ms, prefetched: false)" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=trace msg="emitting websocket event: metadata" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=trace msg="emitting websocket event: active" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="sending successful reply for dealer request" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 17 17:23:07 minidsp-shd volumio[781]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:62wcjED7Q0eZLLtyp2Rlwt","name":"How Far I'll Go - From \"Moana\"/Soundtrack Version","artist_names":["Auli'i Cravalho","Disney"],"album_name":"Disney Summer Songs , Vol. 2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02292f1cb2f64ff5718f3571fc","position":0,"duration":163386,"release_date":"year:2023 month:9 day:25","track_number":20,"disc_number":1}} Jul 17 17:23:07 minidsp-shd volumio[781]: SPOTIFY: received: {"type":"active","data":null} Jul 17 17:23:07 minidsp-shd volumio[781]: info: Aligning Spotify Volume to Volumio Volume Jul 17 17:23:07 minidsp-shd volumio[781]: info: CoreCommandRouter::volumioGetState Jul 17 17:23:07 minidsp-shd volumio[781]: info: CorePlayQueue::getTrack 0 Jul 17 17:23:07 minidsp-shd volumio[781]: info: Setting Spotify Volume from Volumio: 67 Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=trace msg="emitting websocket event: paused" Jul 17 17:23:07 minidsp-shd volumio[781]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:62wcjED7Q0eZLLtyp2Rlwt","play_origin":"playlist"}} Jul 17 17:23:07 minidsp-shd volumio[781]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd volumio[781]: TypeError: Cannot read property 'service' of undefined Jul 17 17:23:07 minidsp-shd volumio[781]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Jul 17 17:23:07 minidsp-shd volumio[781]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:274:18) Jul 17 17:23:07 minidsp-shd volumio[781]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Jul 17 17:23:07 minidsp-shd volumio[781]: at WebSocket.emit (events.js:400:28) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.emit (events.js:400:28) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jul 17 17:23:07 minidsp-shd volumio[781]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 17 17:23:07 minidsp-shd volumio[781]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jul 17 17:23:07 minidsp-shd volumio[781]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jul 17 17:23:07 minidsp-shd volumio[781]: at Socket.emit (events.js:400:28) Jul 17 17:23:07 minidsp-shd volumio[781]: at addChunk (internal/streams/readable.js:293:12) Jul 17 17:23:07 minidsp-shd volumio[781]: at readableAddChunk (internal/streams/readable.js:267:9) Jul 17 17:23:07 minidsp-shd volumio[781]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:07 minidsp-shd go-librespot[1146]: time="2025-07-17T17:23:07+01:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:62wcjED7Q0eZLLtyp2Rlwt" Jul 17 17:23:08 minidsp-shd volumio[1081]: ..................++++ Jul 17 17:23:08 minidsp-shd sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 17:22 Jul 17 17:23:08 minidsp-shd sudo[1332]: 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"