-- Logs begin at Thu 2019-02-14 07:11:58 -03, end at Tue 2026-01-13 22:40:28 -03. -- Jan 13 22:39:00 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Jan 13 22:39:00 minidsp-shd sudo[1041]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:00 minidsp-shd sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 13 22:39:00 minidsp-shd sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:00 minidsp-shd volumiobt[1043]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 13 22:39:00 minidsp-shd sudo[1044]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:00 minidsp-shd volumio[697]: info: Volumio BT Module successfully started Jan 13 22:39:00 minidsp-shd volumio[697]: info: TidalConnect service stoped! Jan 13 22:39:00 minidsp-shd volumio[697]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 13 22:39:00 minidsp-shd volumio[697]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 13 22:39:00 minidsp-shd sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 13 22:39:00 minidsp-shd sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:00 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jan 13 22:39:00 minidsp-shd sudo[1056]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:00 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 13 22:39:00 minidsp-shd volumio[697]: info: Enabling external Volume Control Jan 13 22:39:00 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:39:00 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:39:01 minidsp-shd sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 13 22:39:01 minidsp-shd sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:01 minidsp-shd volumiobt[1043]: pulseaudio: no process found Jan 13 22:39:01 minidsp-shd sudo[1061]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:01 minidsp-shd volumio[697]: info: Executing endpoint tc_getconfig Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 13 22:39:01 minidsp-shd vtcs[1058]: STARTING TidalConnect services, version: 1.3.0.19 Jan 13 22:39:01 minidsp-shd vtcs[1058]: STARTED TidalConnect services. Jan 13 22:39:01 minidsp-shd volumio[697]: info: Executing endpoint tc_connect Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 13 22:39:01 minidsp-shd volumio[697]: info: Connecting to TidalConnect Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.13' (uid=1000 pid=1071 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Jan 13 22:39:01 minidsp-shd volumio[697]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Jan 13 22:39:01 minidsp-shd volumio[697]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jan 13 22:39:01 minidsp-shd mpd[943]: Jan 13 22:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 22:39:01 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 13 22:39:01 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 13 22:39:01 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Successfully called chroot. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Successfully dropped privileges. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Successfully limited resources. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Running. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Canary thread running. Jan 13 22:39:01 minidsp-shd rtkit-daemon[1074]: Watchdog thread running. Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.15' (uid=0 pid=1074 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 13 22:39:01 minidsp-shd systemd[1]: Starting Authorization Manager... Jan 13 22:39:01 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 22:39:01 minidsp-shd systemd[1]: Started Music Player Daemon. Jan 13 22:39:01 minidsp-shd sudo[927]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:01 minidsp-shd volumio[697]: Upnp client error: Error: This socket has been ended by the other party Jan 13 22:39:01 minidsp-shd sudo[939]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:01 minidsp-shd polkitd[1084]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 13 22:39:01 minidsp-shd systemd[1]: Started Authorization Manager. Jan 13 22:39:01 minidsp-shd volumio[697]: error: MPD error: The expression evaluated to a falsy value: Jan 13 22:39:01 minidsp-shd volumio[697]: assert.ok(self.idling) Jan 13 22:39:01 minidsp-shd volumio[697]: error: The expression evaluated to a falsy value: Jan 13 22:39:01 minidsp-shd volumio[697]: assert.ok(self.idling) Jan 13 22:39:01 minidsp-shd volumio[697]: info: MPD running with PID943 Jan 13 22:39:01 minidsp-shd volumio[697]: ,establishing connection Jan 13 22:39:01 minidsp-shd volumio[697]: info: go-librespot daemon successfully initialized Jan 13 22:39:01 minidsp-shd volumio[697]: error: updateQueue error: null Jan 13 22:39:01 minidsp-shd volumio[697]: error: updateQueue error: null Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:01 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:01 minidsp-shd volumio[922]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.35' (uid=1000 pid=1071 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 13 22:39:01 minidsp-shd systemd[1]: Starting Bluetooth service... Jan 13 22:39:01 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 22:39:01 minidsp-shd bluetoothd[1108]: Bluetooth daemon 5.23 Jan 13 22:39:01 minidsp-shd dbus-daemon[424]: [system] Successfully activated service 'org.bluez' Jan 13 22:39:01 minidsp-shd systemd[1]: Started Bluetooth service. Jan 13 22:39:01 minidsp-shd bluetoothd[1108]: Starting SDP server Jan 13 22:39:01 minidsp-shd pulseaudio[1071]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 13 22:39:01 minidsp-shd pulseaudio[1071]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 13 22:39:01 minidsp-shd volumiobt[1043]: Applying permissions Jan 13 22:39:01 minidsp-shd volumiobt[1043]: Setting BT discoverable and pairable Jan 13 22:39:02 minidsp-shd kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 13 22:39:02 minidsp-shd kernel: Bluetooth: BNEP filters: protocol multicast Jan 13 22:39:02 minidsp-shd kernel: Bluetooth: BNEP socket layer initialized Jan 13 22:39:02 minidsp-shd bluetoothd[1108]: Bluetooth management interface 1.14 initialized Jan 13 22:39:02 minidsp-shd dbus-daemon[424]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.36' (uid=0 pid=1108 comm="/usr/local/libexec/bluetooth/bluetoothd ") Jan 13 22:39:02 minidsp-shd pulseaudio[1071]: 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 Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [38B blob data] Jan 13 22:39:02 minidsp-shd volumiobt[1043]: Invalid command Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# agent on Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# discoverable on Jan 13 22:39:02 minidsp-shd volumiobt[1043]: No default controller available Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# pairable on Jan 13 22:39:02 minidsp-shd volumiobt[1043]: No default controller available Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# agent NoInputNoOutput Jan 13 22:39:02 minidsp-shd volumiobt[1043]: Failed to register agent object Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# default-agent Jan 13 22:39:02 minidsp-shd volumiobt[1043]: No agent is registered Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [bluetooth]# quit Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [61B blob data] Jan 13 22:39:02 minidsp-shd volumiobt[1043]: [62B blob data] Jan 13 22:39:02 minidsp-shd systemd[1]: Starting Hostname Service... Jan 13 22:39:02 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 22:39:02 minidsp-shd dbus-daemon[424]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 13 22:39:02 minidsp-shd systemd[1]: Started Hostname Service. Jan 13 22:39:02 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jan 13 22:39:02 minidsp-shd volumio[697]: info: MRS: Getting audio outputs on start Jan 13 22:39:02 minidsp-shd volumio[697]: info: MRS: Requesting all other devices output Jan 13 22:39:02 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:02 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:02 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:02 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:39:03 minidsp-shd volumio[697]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:39:03 minidsp-shd volumio[697]: SPOTIFY: BQCQvuQfrtiFhmPGZNr5OoBpUuUcZ4JBdSgNVla8T6poRoOYloRDv-_MXv55_rp4Np9RXO3Uqed_iLyynP-Bo_Jc_CKcHL9kpH5VgPzHzlHaosqaxmW7Gg-JkFRi8ZyCxaFgyG4lwn4VHnRwQjJ_T7_XV30J_PBHeWqDz6PFZqddUchffWUQA784EMELwcH6xbNPsE9fRusVSHZ4WCA2YzUue4k45Z0r48s3tDwVfIlruM46-JOJSY33SX2gVGHKwejGYNV-a_9uISq1KgHdXw8PbdTBNPeiUaOa6eUXyZLCDkuIlvROhv_f Jan 13 22:39:03 minidsp-shd volumio[697]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 22:39:03 minidsp-shd volumio[697]: info: New Spotify access token = BQCQvuQfrtiFhmPGZNr5OoBpUuUcZ4JBdSgNVla8T6poRoOYloRDv-_MXv55_rp4Np9RXO3Uqed_iLyynP-Bo_Jc_CKcHL9kpH5VgPzHzlHaosqaxmW7Gg-JkFRi8ZyCxaFgyG4lwn4VHnRwQjJ_T7_XV30J_PBHeWqDz6PFZqddUchffWUQA784EMELwcH6xbNPsE9fRusVSHZ4WCA2YzUue4k45Z0r48s3tDwVfIlruM46-JOJSY33SX2gVGHKwejGYNV-a_9uISq1KgHdXw8PbdTBNPeiUaOa6eUXyZLCDkuIlvROhv_f Jan 13 22:39:03 minidsp-shd volumio[697]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 22:39:03 minidsp-shd volumio[697]: info: Volumio called home Jan 13 22:39:03 minidsp-shd volumio[697]: info: TidalConnect service started! Jan 13 22:39:04 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:04-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 13 22:39:04 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:04-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 13 22:39:04 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:04-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 13 22:39:04 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:04-03:00" level=debug msg="zeroconf server listening on port 37439" Jan 13 22:39:04 minidsp-shd volumio[697]: info: Initializing connection to go-librespot Websocket Jan 13 22:39:05 minidsp-shd volumio-remote-updater[431]: [2026-01-13 22:39:05] [connect] Successful connection Jan 13 22:39:05 minidsp-shd volumio-remote-updater[431]: [2026-01-13 22:39:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768354745 101 Jan 13 22:39:05 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jan 13 22:39:06 minidsp-shd sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:39:06 minidsp-shd sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:06 minidsp-shd sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:06 minidsp-shd sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:39:06 minidsp-shd sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:06 minidsp-shd sudo[1120]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:06 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.26 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 4 Jan 13 22:39:06 minidsp-shd sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 22:39:06 minidsp-shd sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:06 minidsp-shd sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:06 minidsp-shd sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 22:39:06 minidsp-shd sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:06 minidsp-shd sudo[1128]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:06 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.26 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Jan 13 22:39:06 minidsp-shd volumio[697]: info: Discovery: adding 2c912bbf-22c3-4ecb-bff3-58de69db3559 Jan 13 22:39:06 minidsp-shd volumio[697]: info: Discovery: Found device miniDSP SHD Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output for this device Jan 13 22:39:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:06 minidsp-shd volumio[697]: info: Adding audio output: Jan 13 22:39:06 minidsp-shd volumio[697]: info: Adding audio output: Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 22:39:06 minidsp-shd volumio[697]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 13 22:39:06 minidsp-shd volumio[697]: info: Listing playlists Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 13 22:39:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 22:39:06 minidsp-shd volumio[697]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 13 22:39:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:39:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:39:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:39:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 22:39:07 minidsp-shd volumio[697]: info: Not Reporting Auto name since its the default one Jan 13 22:39:07 minidsp-shd volumio[697]: info: RAAT Overriding default device vendor model Jan 13 22:39:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 13 22:39:08 minidsp-shd volumio[697]: xcb_connection_has_error() returned true Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jan 13 22:39:08 minidsp-shd volumio[697]: info: updateDSP function in raat called! Jan 13 22:39:08 minidsp-shd volumio[697]: info: Updating RAAT Signal Path Jan 13 22:39:08 minidsp-shd sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 13 22:39:08 minidsp-shd sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:39:08 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jan 13 22:39:08 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 13 22:39:08 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jan 13 22:39:08 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jan 13 22:39:08 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 22:39:08 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 13 22:39:08 minidsp-shd systemd[1]: Started RAAT DAEMON. Jan 13 22:39:08 minidsp-shd sudo[1139]: pam_unix(sudo:session): session closed for user root Jan 13 22:39:08 minidsp-shd volumio[697]: info: Raat Daemon started successfully Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetQueue Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreStateMachine::getQueue Jan 13 22:39:08 minidsp-shd volumio[697]: info: CorePlayQueue::getQueue Jan 13 22:39:08 minidsp-shd volumio[697]: info: Executing endpoint restartRAATSocket Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 13 22:39:08 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 22:39:09 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 13 22:39:09 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 22:39:09 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:09-03:00" level=debug msg="obtained new client token: AACy9IgejRhLBPQQZQbyE1qPHPO8gJhF7lkHcFfRytGUoeyzMKpZoptPfZrZUkMqVb+TAoPE5B6CsAC+0rew4xXlZjieiht0LX/E2hGrV+Tqhxi9uGC3iW62EsS+NXAS0GjmJ5rPth8VwALYqtdt2WLCf9HGpzszp/lNDkegntGJv7h8TJgUNOuVOiy/52mBsegn38k9Sp9wfZ18gXHGijqLKNY47SB/Zf57hUGClPUa0SUA39Pi46BDthVF" Jan 13 22:39:10 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:10 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 13 22:39:10 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:15 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:15-03:00" level=info msg="connected to ap-guc3.spotify.com:4070" Jan 13 22:39:15 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:15-03:00" level=debug msg="completed keyexchange" Jan 13 22:39:15 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:15-03:00" level=debug msg="completed challenge" Jan 13 22:39:15 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:15-03:00" level=debug msg="authenticated as alentorres-cl" Jan 13 22:39:17 minidsp-shd volumio[697]: info: Access Token successfully retrieved Jan 13 22:39:17 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:39:17 minidsp-shd volumio[697]: info: [1768354757414] CoreMusicLibrary::Adding element QOBUZ Jan 13 22:39:17 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:17 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:17 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:17 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:17 minidsp-shd volumio[697]: info: Stopping AccessToken refresher cron for QOBUZ Jan 13 22:39:17 minidsp-shd volumio[697]: info: AccessToken refresher cron started for QOBUZ Jan 13 22:39:17 minidsp-shd volumio[697]: info: Adding QOBUZ REST API Endpoints Jan 13 22:39:17 minidsp-shd volumio[697]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 13 22:39:17 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jan 13 22:39:17 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Jan 13 22:39:18 minidsp-shd volumio[697]: info: Access Token successfully retrieved Jan 13 22:39:21 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:21-03:00" level=debug msg="authenticated as alentorres-cl" Jan 13 22:39:21 minidsp-shd volumio[922]: ..................................................................................................................................................................................................................++++ Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:21 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:21 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:21 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:21 minidsp-shd volumio[697]: info: Received STOP, but no service to execute it Jan 13 22:39:21 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"USB","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:21 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:21 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:21 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:21 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:22 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:22-03:00" level=debug msg="new websocket client" Jan 13 22:39:22 minidsp-shd volumio[697]: info: Connection to go-librespot Websocket established Jan 13 22:39:23 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 13 22:39:23 minidsp-shd volumio[697]: SPOTIFY: User informations: {"country":"CL","display_name":"alentorres-cl","email":"alen_torres@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/alentorres-cl"},"followers":{"href":null,"total":12},"href":"https://api.spotify.com/v1/users/alentorres-cl","id":"alentorres-cl","images":[],"product":"premium","type":"user","uri":"spotify:user:alentorres-cl"} Jan 13 22:39:23 minidsp-shd volumio[697]: info: Spotify Successfully logged in Jan 13 22:39:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:39:23 minidsp-shd volumio[697]: info: [1768354763324] CoreMusicLibrary::Adding element Spotify Jan 13 22:39:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:23 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:23 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:23 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:23 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:25 minidsp-shd volumio[697]: info: Getting Spotify volume Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 22:39:25 minidsp-shd volumio[697]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 13 22:39:25 minidsp-shd volumio[697]: info: Received Get System Version Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:39:25 minidsp-shd volumio[697]: info: Received Get System Info Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 22:39:25 minidsp-shd volumio[697]: info: Discovery: Getting this device information Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:25 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 22:39:26 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:26-03:00" level=debug msg="dealer connection opened" Jan 13 22:39:26 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:26-03:00" level=debug msg="initializing zeroconf session, username: alentorres-cl" Jan 13 22:39:26 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:26-03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 22:39:26 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:26-03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 22:39:26 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:26-03:00" level=debug msg="received connection id: YzY0ZjdiZjgtNzA2My00YTMxLThmODktZmJiMDU2MDY3M2EwK2RlYWxlcit0Y3A6Ly8wYWIxNDgzNi5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArN0ZBNTkyQUQwNTVGQUIzQjI5RDgxRDE0OEYxNkJGRDE5ODk5NTgzNTVBRkJEREY0RENDNzFFRTEyQjcyRUI4NQ==" Jan 13 22:39:26 minidsp-shd volumio[697]: info: Spotify volume: 100 Jan 13 22:39:27 minidsp-shd volumio[922]: ..................................................................++++ Jan 13 22:39:27 minidsp-shd volumio[922]: e is 65537 (0x010001) Jan 13 22:39:27 minidsp-shd volumio[922]: writing RSA key Jan 13 22:39:27 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 22:39:27 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:39:27 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 22:39:27 minidsp-shd volumio[697]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Jan 13 22:39:27 minidsp-shd volumio[697]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Jan 13 22:39:27 minidsp-shd volumio[697]: info: ------------------------------------------- Jan 13 22:39:27 minidsp-shd volumio[697]: info: ----- MyVolumio plugins startup ---- Jan 13 22:39:27 minidsp-shd volumio[697]: info: ------------------------------------------- Jan 13 22:39:27 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:28 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:28 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:28 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:28 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::serviceStop Jan 13 22:39:28 minidsp-shd volumio[697]: info: [1768354768139] inputs::stop Jan 13 22:39:28 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"XLR","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:28 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:28 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:28 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:28 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:29 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:29 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:29 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:29 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::serviceStop Jan 13 22:39:29 minidsp-shd volumio[697]: info: [1768354769246] inputs::stop Jan 13 22:39:29 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"RCA","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:29 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:29 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:29 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:29 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::serviceStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: [1768354770090] inputs::stop Jan 13 22:39:30 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"AES-EBU","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:30 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:30 minidsp-shd ntpd[677]: Soliciting pool server 200.89.75.197 Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:30 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:30 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:30 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreCommandRouter::serviceStop Jan 13 22:39:30 minidsp-shd volumio[697]: info: [1768354770872] inputs::stop Jan 13 22:39:30 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"SPDIF","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:30 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:30 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:30 minidsp-shd ntpd[677]: Soliciting pool server 64.176.4.213 Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:31 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:39:31 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:39:31 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:39:31 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:39:31 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioStop Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreStateMachine::stop Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreStateMachine::serviceStop Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::serviceStop Jan 13 22:39:31 minidsp-shd volumio[697]: info: [1768354771486] inputs::stop Jan 13 22:39:31 minidsp-shd volumio[697]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:39:31 minidsp-shd volumio[697]: info: Preload queue cleared Jan 13 22:39:31 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:31-03:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 22:39:31 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:31-03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 22:39:31 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:31-03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 22:39:31 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:31-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 22:39:31 minidsp-shd go-librespot[988]: time="2026-01-13T22:39:31-03:00" level=debug msg="autoplay enabled: false" Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::servicePushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:39:31 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:39:31 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:39:31 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:39:32 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:39:32 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Jan 13 22:39:35 minidsp-shd ntpd[677]: Soliciting pool server 200.1.19.16 Jan 13 22:39:35 minidsp-shd ntpd[677]: Soliciting pool server 162.159.200.1 Jan 13 22:39:36 minidsp-shd ntpd[677]: Soliciting pool server 2606:4700:f1::1 Jan 13 22:39:37 minidsp-shd volumio[697]: info: Successfully retrieved User Session From TIDAL Jan 13 22:39:59 minidsp-shd ntpd[677]: Soliciting pool server 194.0.5.123 Jan 13 22:39:59 minidsp-shd ntpd[677]: Soliciting pool server 64.176.3.116 Jan 13 22:40:01 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Jan 13 22:40:04 minidsp-shd ntpd[677]: Soliciting pool server 162.159.200.123 Jan 13 22:40:04 minidsp-shd ntpd[677]: Soliciting pool server 64.176.13.132 Jan 13 22:40:06 minidsp-shd volumio[697]: error: MyVolumio Plugin failed to start in a timely fashion Jan 13 22:40:06 minidsp-shd volumio[697]: info: BOOT COMPLETED Jan 13 22:40:06 minidsp-shd volumio[697]: [Metrics] CommandRouter: 84s 360.40ms Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetStartupVolume Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 22:40:06 minidsp-shd volumio[697]: info: VolumeController:: Setting startup Volume 95 Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::Close All Modals sent Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::Close All Modals sent Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:06 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:06 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:40:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 13 22:40:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 13 22:40:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 13 22:40:07 minidsp-shd volumio[697]: xcb_connection_has_error() returned true Jan 13 22:40:07 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 13 22:40:14 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioNext Jan 13 22:40:14 minidsp-shd volumio[697]: info: CoreStateMachine::next Jan 13 22:40:14 minidsp-shd volumio[697]: info: WARNING: No next method for plugin inputs Jan 13 22:40:15 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:16 minidsp-shd volumio[697]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 13 22:40:21 minidsp-shd volumio[697]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Jan 13 22:40:21 minidsp-shd volumio[697]: SPOTIFY: SPOTIFY VOLUME 100 Jan 13 22:40:21 minidsp-shd volumio[697]: SPOTIFY: VOLUMIO VOLUME 95 Jan 13 22:40:21 minidsp-shd volumio[697]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 13 22:40:21 minidsp-shd volumio[697]: info: Setting Spotify Volume from Volumio: 95 Jan 13 22:40:21 minidsp-shd volumio[697]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Jan 13 22:40:21 minidsp-shd volumio[697]: info: Successfully retrieved User Subscription From TIDAL Jan 13 22:40:21 minidsp-shd volumio[697]: info: Adding TIDAL to Browse Sources Jan 13 22:40:21 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 22:40:21 minidsp-shd volumio[697]: info: [1768354821265] CoreMusicLibrary::Adding element TIDAL Jan 13 22:40:21 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 22:40:21 minidsp-shd volumio[697]: Cannot find translation for source YouTube Music Jan 13 22:40:21 minidsp-shd volumio[697]: Cannot find translation for source Presets Jan 13 22:40:21 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Jan 13 22:40:21 minidsp-shd volumio[697]: Cannot find translation for source Spotify Jan 13 22:40:21 minidsp-shd volumio[697]: Cannot find translation for source TIDAL Jan 13 22:40:21 minidsp-shd volumio[697]: info: Stopping AccessToken refresher cron Jan 13 22:40:21 minidsp-shd volumio[697]: info: AccessToken refresher cron started Jan 13 22:40:21 minidsp-shd volumio[697]: info: Adding TIDAL REST API Endpoints Jan 13 22:40:21 minidsp-shd volumio[697]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 13 22:40:22 minidsp-shd volumio[697]: SPOTIFY: SETTING SPOTIFY VOLUME 95 Jan 13 22:40:22 minidsp-shd volumio[697]: info: Sending Spotify command with payload to local API: /player/volume Jan 13 22:40:22 minidsp-shd go-librespot[988]: time="2026-01-13T22:40:22-03:00" level=debug msg="update volume to 62258/65535" Jan 13 22:40:22 minidsp-shd go-librespot[988]: time="2026-01-13T22:40:22-03:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 13 22:40:22 minidsp-shd go-librespot[988]: time="2026-01-13T22:40:22-03:00" level=trace msg="emitting websocket event: volume" Jan 13 22:40:22 minidsp-shd volumio[697]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} Jan 13 22:40:22 minidsp-shd volumio[697]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:40:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:23 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:24 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:24 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Jan 13 22:40:24 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Jan 13 22:40:24 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Jan 13 22:40:24 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output update for this device Jan 13 22:40:24 minidsp-shd volumio[697]: info: MRS: Pushing multiroomSync output Jan 13 22:40:24 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jan 13 22:40:27 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioSeek Jan 13 22:40:27 minidsp-shd volumio[697]: info: CoreStateMachine::seek Jan 13 22:40:27 minidsp-shd volumio[697]: info: [1768354827466] inputs::seek to 0 Jan 13 22:40:27 minidsp-shd volumio[697]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:40:27 minidsp-shd volumio[697]: TypeError: this.sendSpopCommand is not a function Jan 13 22:40:27 minidsp-shd volumio[697]: at inputs.seek (/volumio/app/plugins/music_service/inputs/inputs_real:1:2486) Jan 13 22:40:27 minidsp-shd volumio[697]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1084:20) Jan 13 22:40:27 minidsp-shd volumio[697]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1421:28) Jan 13 22:40:27 minidsp-shd volumio[697]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:153:33) Jan 13 22:40:27 minidsp-shd volumio[697]: at Socket.emit (events.js:400:28) Jan 13 22:40:27 minidsp-shd volumio[697]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 13 22:40:27 minidsp-shd volumio[697]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jan 13 22:40:27 minidsp-shd volumio[697]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:40:28 minidsp-shd sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 22:39 Jan 13 22:40:28 minidsp-shd sudo[1220]: 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:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"