-- Logs begin at Sun 2025-06-15 23:28:07 +08, end at Sun 2025-06-15 23:30:34 +08. -- Jun 15 23:29:00 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:00 minidsp-shd systemd[1]: wireless.service: Succeeded. Jun 15 23:29:00 minidsp-shd systemd[1]: Started Wireless Services. Jun 15 23:29:00 minidsp-shd sudo[1213]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:29:00 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:29:00 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:00 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 15 23:29:00 minidsp-shd volumio[628]: info: Enabling external Volume Control Jun 15 23:29:00 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:00 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:00 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:00 minidsp-shd sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd sudo[1255]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd volumio[628]: info: Starting Shairport Sync Jun 15 23:29:00 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 15 23:29:00 minidsp-shd sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:29:00 minidsp-shd sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:00 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:29:00 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:29:00 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:00 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:00 minidsp-shd sudo[1264]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:00 minidsp-shd sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 15 23:29:00 minidsp-shd sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:00 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:00 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 15 23:29:00 minidsp-shd sudo[1271]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:00 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:00 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:00 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:00 minidsp-shd sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 15 23:29:00 minidsp-shd sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:00 minidsp-shd volumiobt[1242]: pulseaudio: no process found Jun 15 23:29:00 minidsp-shd sudo[1284]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:00 minidsp-shd volumio[628]: info: TidalConnect service stoped! Jun 15 23:29:00 minidsp-shd volumio[628]: info: Preparing to generate the ALSA configuration file Jun 15 23:29:00 minidsp-shd dbus-daemon[470]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=1293 comm="/usr/bin/pulseaudio --high-priority=true ") Jun 15 23:29:00 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 15 23:29:01 minidsp-shd dbus-daemon[470]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 15 23:29:01 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Successfully called chroot. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Successfully dropped privileges. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Successfully limited resources. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Running. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Watchdog thread running. Jun 15 23:29:01 minidsp-shd rtkit-daemon[1294]: Canary thread running. Jun 15 23:29:01 minidsp-shd dbus-daemon[470]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=1294 comm="/usr/lib/rtkit/rtkit-daemon ") Jun 15 23:29:01 minidsp-shd systemd[1]: Starting Authorization Manager... Jun 15 23:29:01 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jun 15 23:29:01 minidsp-shd volumio[628]: info: Raat Daemon started successfully Jun 15 23:29:01 minidsp-shd volumio[628]: info: Shairport-Sync Started Jun 15 23:29:01 minidsp-shd volumio[628]: Error adding Membership: Error: addMembership EINVAL Jun 15 23:29:01 minidsp-shd volumio[628]: info: Shairport-Sync Started Jun 15 23:29:01 minidsp-shd volumio[628]: info: Shairport-Sync Started Jun 15 23:29:01 minidsp-shd volumio[628]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 15 23:29:01 minidsp-shd volumio[628]: info: Reading ALSA contributions from plugins. Jun 15 23:29:01 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:01 minidsp-shd polkitd[1297]: started daemon version 0.105 using authority implementation `local' version `0.105' Jun 15 23:29:01 minidsp-shd dbus-daemon[470]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jun 15 23:29:01 minidsp-shd systemd[1]: Started Authorization Manager. Jun 15 23:29:01 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:01 minidsp-shd volumio[628]: info: Executing endpoint restartRAATSocket Jun 15 23:29:01 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 15 23:29:01 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:01 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:01 minidsp-shd volumio[628]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.100 UA: Mozilla/5.0 (Linux; Android 15; SM-S926U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Total Clients: 7 Jun 15 23:29:01 minidsp-shd volumio[628]: info: MRS: Getting audio outputs on start Jun 15 23:29:01 minidsp-shd volumio[628]: info: MRS: Requesting all other devices output Jun 15 23:29:01 minidsp-shd volumio[628]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34.5,"mute":false,"disableVolumeControl":false} Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:02 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:02 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:02 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Jun 15 23:29:02 minidsp-shd volumio[628]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:02 minidsp-shd volumio[628]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 15 23:29:02 minidsp-shd volumiobt[1242]: Applying permissions Jun 15 23:29:02 minidsp-shd volumiobt[1242]: Setting BT discoverable and pairable Jun 15 23:29:02 minidsp-shd pulseaudio[1293]: 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 Jun 15 23:29:02 minidsp-shd sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 15 23:29:02 minidsp-shd sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:02 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jun 15 23:29:02 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jun 15 23:29:02 minidsp-shd sudo[1310]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [38B blob data] Jun 15 23:29:02 minidsp-shd volumiobt[1242]: Invalid command Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# agent on Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# discoverable on Jun 15 23:29:02 minidsp-shd volumiobt[1242]: No default controller available Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# pairable on Jun 15 23:29:02 minidsp-shd volumiobt[1242]: No default controller available Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# agent NoInputNoOutput Jun 15 23:29:02 minidsp-shd volumiobt[1242]: Failed to register agent object Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# default-agent Jun 15 23:29:02 minidsp-shd volumiobt[1242]: No agent is registered Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [bluetooth]# quit Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [61B blob data] Jun 15 23:29:02 minidsp-shd volumio[628]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34.5,"mute":false,"disableVolumeControl":false} Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:02 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:02 minidsp-shd volumiobt[1242]: [62B blob data] Jun 15 23:29:02 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:02 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:02 minidsp-shd volumio[628]: error: Help! Some callbacks for volumioPushState are crashing! Jun 15 23:29:02 minidsp-shd volumio[628]: error: Cannot read property 'sendVolumeMute' of undefined Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:02 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 23:29:02 minidsp-shd volumio[628]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 15 23:29:02 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:02 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:02 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:02 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:02 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:02 minidsp-shd volumio[628]: info: Listing playlists Jun 15 23:29:02 minidsp-shd volumio[628]: info: Discovery: A device disappeared from network Jun 15 23:29:03 minidsp-shd volumio[628]: info: Asound.conf file written Jun 15 23:29:03 minidsp-shd sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 15 23:29:03 minidsp-shd sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:03 minidsp-shd sudo[1317]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:03 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:03 minidsp-shd volumio[628]: No state is present for card Out Jun 15 23:29:03 minidsp-shd volumio[628]: Found hardware: "I2S_Out" "" "" "" "" Jun 15 23:29:03 minidsp-shd volumio[628]: Hardware is initialized using a generic method Jun 15 23:29:03 minidsp-shd volumio[628]: No state is present for card Out Jun 15 23:29:03 minidsp-shd volumio[628]: No state is present for card SPDIF Jun 15 23:29:03 minidsp-shd volumio[628]: Found hardware: "SPDIF" "" "" "" "" Jun 15 23:29:03 minidsp-shd volumio[628]: Hardware is initialized using a generic method Jun 15 23:29:03 minidsp-shd volumio[628]: No state is present for card SPDIF Jun 15 23:29:03 minidsp-shd volumio[628]: info: Output device has changed, restarting MPD Jun 15 23:29:03 minidsp-shd volumio[628]: info: Output device has changed, restarting Shairport Sync Jun 15 23:29:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:03 minidsp-shd sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 23:29:03 minidsp-shd sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:03 minidsp-shd sudo[1322]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:03 minidsp-shd sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 23:29:03 minidsp-shd sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:03 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:03 minidsp-shd systemd[1]: mpd.service: Succeeded. Jun 15 23:29:03 minidsp-shd systemd[1]: Stopped Music Player Daemon. Jun 15 23:29:03 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:03 minidsp-shd volumio[628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 23:29:03 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:03 minidsp-shd volumio[628]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 15 23:29:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:03 minidsp-shd systemd[1]: Starting Music Player Daemon... Jun 15 23:29:03 minidsp-shd volumio[628]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 15 23:29:03 minidsp-shd volumio[628]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2090:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jun 15 23:29:03 minidsp-shd volumio[628]: info: Preparing to generate the ALSA configuration file Jun 15 23:29:04 minidsp-shd sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 15 23:29:04 minidsp-shd sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:04 minidsp-shd systemd[1]: Stopping Volumio Tidal Connect Service... Jun 15 23:29:04 minidsp-shd volumio[628]: info: Discovery: Started advertising with name: miniDSP SHD Jun 15 23:29:04 minidsp-shd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:04 minidsp-shd systemd[1]: vtcs.service: Succeeded. Jun 15 23:29:04 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Jun 15 23:29:04 minidsp-shd sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:04 minidsp-shd volumio[628]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 15 23:29:04 minidsp-shd volumio[628]: info: Reading ALSA contributions from plugins. Jun 15 23:29:04 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:04 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:04 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:04 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:04 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:04 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:04 minidsp-shd volumio[628]: info: Executing endpoint tc_getconfig Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 15 23:29:04 minidsp-shd volumio[628]: info: MPD Permissions set Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: Not Reporting Auto name since its the default one Jun 15 23:29:04 minidsp-shd volumio[628]: info: RAAT Overriding default device vendor model Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 15 23:29:04 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:04 minidsp-shd volumio[628]: info: Starting Shairport Sync Jun 15 23:29:04 minidsp-shd sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 15 23:29:04 minidsp-shd volumio[628]: info: Asound.conf file unchanged, so no further update is needed Jun 15 23:29:04 minidsp-shd sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:04 minidsp-shd volumio[628]: info: Output device has changed, restarting MPD Jun 15 23:29:04 minidsp-shd sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:29:04 minidsp-shd sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:04 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jun 15 23:29:04 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:04 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jun 15 23:29:04 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jun 15 23:29:04 minidsp-shd sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 23:29:04 minidsp-shd sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:04 minidsp-shd volumio[628]: info: Output device has changed, restarting Shairport Sync Jun 15 23:29:04 minidsp-shd sudo[1358]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:04 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:05 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:29:05 minidsp-shd sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 23:29:05 minidsp-shd sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:05 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 15 23:29:05 minidsp-shd sudo[1352]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:05 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:29:05 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:05 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:05 minidsp-shd sudo[1354]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:05 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:05 minidsp-shd volumio[628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 23:29:05 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:05 minidsp-shd volumio[628]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:05 minidsp-shd systemd[1]: mpd.service: Succeeded. Jun 15 23:29:05 minidsp-shd systemd[1]: Stopped Music Player Daemon. Jun 15 23:29:05 minidsp-shd volumio[628]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 15 23:29:05 minidsp-shd volumio[628]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2090:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jun 15 23:29:05 minidsp-shd systemd[1]: Starting Music Player Daemon... Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:05 minidsp-shd sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 15 23:29:05 minidsp-shd sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:05 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:05 minidsp-shd volumio[628]: info: MPD Permissions set Jun 15 23:29:05 minidsp-shd volumio[628]: info: TidalConnect service started! Jun 15 23:29:05 minidsp-shd volumio[628]: info: Shairport-Sync Started Jun 15 23:29:05 minidsp-shd volumio[628]: info: Raat Daemon started successfully Jun 15 23:29:05 minidsp-shd sudo[1383]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:05 minidsp-shd volumio[628]: info: Executing endpoint restartRAATSocket Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:05 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Jun 15 23:29:05 minidsp-shd volumio[628]: info: Starting Shairport Sync Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 15 23:29:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 15 23:29:05 minidsp-shd sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 15 23:29:05 minidsp-shd sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:05 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 15 23:29:05 minidsp-shd sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:05 minidsp-shd sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:05 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 15 23:29:05 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd sudo[1397]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: Not Reporting Auto name since its the default one Jun 15 23:29:06 minidsp-shd volumio[628]: info: RAAT Overriding default device vendor model Jun 15 23:29:06 minidsp-shd sudo[1394]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:06 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:06 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:06 minidsp-shd volumio[628]: info: Shairport-Sync Started Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:06 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:06 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 15 23:29:06 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:06 minidsp-shd sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 15 23:29:06 minidsp-shd sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:06 minidsp-shd volumio[628]: verbose: New Socket.io Connection to 192.168.1.122:3000 from 192.168.1.100 UA: Dart/3.5 (dart:io) Total Clients: 9 Jun 15 23:29:06 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:06 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:06 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:06 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jun 15 23:29:06 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jun 15 23:29:06 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:06 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 15 23:29:06 minidsp-shd sudo[1411]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 15 23:29:06 minidsp-shd volumio[628]: info: Not Reporting Auto name since its the default one Jun 15 23:29:06 minidsp-shd volumio[628]: info: RAAT Overriding default device vendor model Jun 15 23:29:06 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:06 minidsp-shd volumio[628]: info: Raat Daemon started successfully Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:06 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:06 minidsp-shd volumio[628]: info: Executing endpoint restartRAATSocket Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 15 23:29:06 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 15 23:29:06 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:06 minidsp-shd sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 15 23:29:06 minidsp-shd sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:06 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jun 15 23:29:06 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:06 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jun 15 23:29:06 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 15 23:29:07 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 15 23:29:07 minidsp-shd sudo[1431]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:07 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:07 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jun 15 23:29:07 minidsp-shd volumio[628]: info: updateDSP function in raat called! Jun 15 23:29:07 minidsp-shd volumio[628]: info: Updating RAAT Signal Path Jun 15 23:29:07 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jun 15 23:29:07 minidsp-shd volumio[628]: info: Raat Daemon started successfully Jun 15 23:29:07 minidsp-shd volumio-remote-updater[448]: No test mode Jun 15 23:29:07 minidsp-shd volumio-remote-updater[448]: No alpha test mode Jun 15 23:29:07 minidsp-shd volumio[628]: info: Discovery: adding undefined Jun 15 23:29:07 minidsp-shd volumio[628]: info: Discovery: Found device miniDSP SHD Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:07 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:07 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:07 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:07 minidsp-shd volumio[628]: info: TidalConnect service stoped! Jun 15 23:29:07 minidsp-shd volumio[628]: info: Executing endpoint restartRAATSocket Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:07 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:07 minidsp-shd volumio[628]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:07 minidsp-shd volumio[628]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:07 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jun 15 23:29:07 minidsp-shd sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 15 23:29:07 minidsp-shd sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:07 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:07 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:07 minidsp-shd sudo[1456]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:07 minidsp-shd volumio[628]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 15 23:29:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 15 23:29:08 minidsp-shd volumio[628]: info: Executing endpoint tc_getconfig Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 15 23:29:08 minidsp-shd vtcs[1458]: STARTING TidalConnect services, version: 1.3.0.19 Jun 15 23:29:08 minidsp-shd vtcs[1458]: STARTED TidalConnect services. Jun 15 23:29:08 minidsp-shd volumio[628]: info: Executing endpoint tc_connect Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 15 23:29:08 minidsp-shd volumio[628]: info: Connecting to TidalConnect Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::servicePushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: 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} Jun 15 23:29:08 minidsp-shd volumio[628]: verbose: CURRENT POSITION 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::syncState stateService stop Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::syncState currentStatus stop Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: No code Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::servicePushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: 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} Jun 15 23:29:08 minidsp-shd volumio[628]: verbose: CURRENT POSITION 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::syncState stateService stop Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::syncState currentStatus stop Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: No code Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:08 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:08 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:08 minidsp-shd volumio[628]: info: TidalConnect service stoped! Jun 15 23:29:08 minidsp-shd volumio[628]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:08 minidsp-shd volumio[628]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 15 23:29:09 minidsp-shd sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 15 23:29:09 minidsp-shd sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:09 minidsp-shd sudo[1485]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd mpd[1381]: Jun 15 15:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 15 23:29:10 minidsp-shd mpd[1381]: Jun 15 15:29 : exception: Database corrupted Jun 15 23:29:10 minidsp-shd systemd[1]: Started Music Player Daemon. Jun 15 23:29:10 minidsp-shd volumio[628]: Upnp client error: Error: This socket has been ended by the other party Jun 15 23:29:10 minidsp-shd volumio[628]: Upnp client error: Error: This socket has been ended by the other party Jun 15 23:29:10 minidsp-shd sudo[1143]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd sudo[1157]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd sudo[1361]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd sudo[1325]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd volumio[628]: Upnp client error: Error: This socket has been ended by the other party Jun 15 23:29:10 minidsp-shd volumio[628]: info: Completed starting Core Plugins Jun 15 23:29:10 minidsp-shd volumio[628]: info: ------------------------------------------- Jun 15 23:29:10 minidsp-shd volumio[628]: info: ----- MyVolumio plugins startup ---- Jun 15 23:29:10 minidsp-shd volumio[628]: info: ------------------------------------------- Jun 15 23:29:10 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 15 23:29:10 minidsp-shd volumio[628]: info: MPD running with PID1381 Jun 15 23:29:10 minidsp-shd volumio[628]: ,establishing connection Jun 15 23:29:10 minidsp-shd volumio[628]: info: TidalConnect service started! Jun 15 23:29:10 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 15 23:29:10 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 15 23:29:10 minidsp-shd volumio[628]: info: Refreshing Cached IP Addresses Jun 15 23:29:10 minidsp-shd sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 15 23:29:10 minidsp-shd sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:10 minidsp-shd sudo[1532]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:10 minidsp-shd systemd[1]: upmpdcli.service: Main process exited, code=killed, status=15/TERM Jun 15 23:29:10 minidsp-shd sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:29:10 minidsp-shd systemd[1]: upmpdcli.service: Succeeded. Jun 15 23:29:10 minidsp-shd sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:10 minidsp-shd sudo[1534]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:11 minidsp-shd sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:11 minidsp-shd sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:11 minidsp-shd sudo[1537]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:11 minidsp-shd volumio[628]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: ControllerMpd::sendMpdCommand status Jun 15 23:29:11 minidsp-shd volumio[628]: info: Jun 15 23:29:11 minidsp-shd volumio[628]: ---------------------------- MPD announces state update: update Jun 15 23:29:11 minidsp-shd volumio[628]: info: ControllerMpd::getState Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: ControllerMpd::sendMpdCommand status Jun 15 23:29:11 minidsp-shd volumio[628]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: MPD error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: The expression evaluated to a falsy value: Jun 15 23:29:11 minidsp-shd volumio[628]: assert.ok(self.idling) Jun 15 23:29:11 minidsp-shd volumio[628]: error: updateQueue error: null Jun 15 23:29:11 minidsp-shd volumio[628]: info: sendMpdCommand status took 46 milliseconds Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: ControllerMpd::parseState Jun 15 23:29:11 minidsp-shd volumio[628]: info: Command Router : Notfying DB Updatefalse Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:29:11 minidsp-shd volumio[628]: info: sendMpdCommand status took 175 milliseconds Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: ControllerMpd::parseState Jun 15 23:29:11 minidsp-shd volumio[628]: info: ControllerMpd::pushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::servicePushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:11 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 15 23:29:11 minidsp-shd volumio[628]: verbose: CURRENT POSITION 0 Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreStateMachine::syncState stateService stop Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreStateMachine::syncState currentStatus stop Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:11 minidsp-shd volumio[628]: info: No code Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreStateMachine::pushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:11 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioPushState Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output update for this device Jun 15 23:29:11 minidsp-shd volumio[628]: info: MRS: Pushing multiroomSync output Jun 15 23:29:11 minidsp-shd volumio[628]: info: ------------------------------ 264ms Jun 15 23:29:12 minidsp-shd volumio[628]: info: TidalConnect service started! Jun 15 23:29:14 minidsp-shd dhcpcd[838]: eth0: offered 192.168.1.122 from 192.168.1.254 Jun 15 23:29:14 minidsp-shd dhcpcd[838]: eth0: leased 192.168.1.122 for 14400 seconds Jun 15 23:29:14 minidsp-shd dhcpcd[838]: eth0: adding route to 192.168.1.0/24 Jun 15 23:29:14 minidsp-shd dhcpcd[838]: eth0: adding default route via 192.168.1.254 Jun 15 23:29:15 minidsp-shd ntpd[1092]: ntpd exiting on signal 15 (Terminated) Jun 15 23:29:15 minidsp-shd ntpd[1092]: 172.104.34.44 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd systemd[1]: Stopping Network Time Service... Jun 15 23:29:15 minidsp-shd ntpd[1092]: 162.159.200.1 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd systemd[1]: ntp.service: Succeeded. Jun 15 23:29:15 minidsp-shd ntpd[1092]: 162.159.200.123 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd ntpd[1092]: 17.253.60.253 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd ntpd[1092]: 23.106.249.200 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd ntpd[1092]: 137.184.250.82 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd systemd[1]: Stopped Network Time Service. Jun 15 23:29:15 minidsp-shd ntpd[1092]: 54.251.47.36 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd ntpd[1092]: 94.237.79.110 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd ntpd[1092]: 106.10.186.200 local addr 192.168.1.122 -> Jun 15 23:29:15 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:15 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 15 23:29:15 minidsp-shd systemd[1]: Starting Network Time Service... Jun 15 23:29:15 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:15 minidsp-shd ntpd[1575]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 15 23:29:15 minidsp-shd ntpd[1575]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 15 23:29:15 minidsp-shd systemd[1]: Started Network Time Service. Jun 15 23:29:15 minidsp-shd ntpd[1581]: proto: precision = 0.875 usec (-20) Jun 15 23:29:15 minidsp-shd ntpd[1581]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 15 23:29:15 minidsp-shd ntpd[1581]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 15 23:29:15 minidsp-shd ntpd[1581]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 901 days ago Jun 15 23:29:15 minidsp-shd ntpd[1581]: Listen and drop on 0 v6wildcard [::]:123 Jun 15 23:29:15 minidsp-shd ntpd[1581]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 15 23:29:15 minidsp-shd ntpd[1581]: Listen normally on 2 lo 127.0.0.1:123 Jun 15 23:29:15 minidsp-shd ntpd[1581]: Listen normally on 3 eth0 192.168.1.122:123 Jun 15 23:29:15 minidsp-shd ntpd[1581]: Listening on routing socket on fd #20 for interface updates Jun 15 23:29:15 minidsp-shd ntpd[1581]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 15 23:29:15 minidsp-shd ntpd[1581]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 15 23:29:16 minidsp-shd volumio[628]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 15 23:29:16 minidsp-shd ntpd[1581]: Soliciting pool server 203.123.48.1 Jun 15 23:29:17 minidsp-shd ntpd[1581]: Soliciting pool server 162.159.200.123 Jun 15 23:29:17 minidsp-shd ntpd[1581]: Soliciting pool server 162.159.200.1 Jun 15 23:29:18 minidsp-shd ntpd[1581]: Soliciting pool server 172.104.34.44 Jun 15 23:29:18 minidsp-shd ntpd[1581]: Soliciting pool server 45.77.171.102 Jun 15 23:29:18 minidsp-shd ntpd[1581]: Soliciting pool server 17.253.60.253 Jun 15 23:29:19 minidsp-shd ntpd[1581]: Soliciting pool server 106.10.186.200 Jun 15 23:29:19 minidsp-shd ntpd[1581]: Soliciting pool server 94.237.79.110 Jun 15 23:29:19 minidsp-shd ntpd[1581]: Soliciting pool server 106.10.186.201 Jun 15 23:29:19 minidsp-shd ntpd[1581]: Soliciting pool server 137.184.250.82 Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 15 23:29:19 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 15 23:29:20 minidsp-shd ntpd[1581]: Soliciting pool server 54.251.47.36 Jun 15 23:29:20 minidsp-shd ntpd[1581]: Soliciting pool server 23.106.249.200 Jun 15 23:29:21 minidsp-shd ntpd[1581]: Soliciting pool server 2a01:4ff:2f0:21f5::1 Jun 15 23:29:21 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 15 23:29:21 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 15 23:29:21 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:21 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:21 minidsp-shd volumio[628]: info: Starting MyVolumio Remote Streaming Endpoints Jun 15 23:29:21 minidsp-shd volumio[628]: info: MyVolumio not started Jun 15 23:29:21 minidsp-shd volumio[628]: info: Initializing device activation check Jun 15 23:29:21 minidsp-shd volumio[628]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 15 23:29:21 minidsp-shd volumio[628]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jun 15 23:29:21 minidsp-shd sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:29:21 minidsp-shd sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:21 minidsp-shd sudo[1584]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:21 minidsp-shd sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:21 minidsp-shd sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:21 minidsp-shd sudo[1586]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:21 minidsp-shd sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 15 23:29:21 minidsp-shd sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:22 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Jun 15 23:29:22 minidsp-shd sudo[1592]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:22 minidsp-shd volumio[628]: info: Upmpdcli Daemon Started Jun 15 23:29:22 minidsp-shd volumio[1594]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 15 23:29:23 minidsp-shd volumio[1594]: ......................++++ Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:29:27 minidsp-shd volumio-remote-updater[448]: No test mode Jun 15 23:29:27 minidsp-shd volumio-remote-updater[448]: No alpha test mode Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 15 23:29:27 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:27 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:27 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:27 minidsp-shd volumio[628]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 15 23:29:27 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 15 23:29:30 minidsp-shd volumio[1594]: ......................................................................................++++ Jun 15 23:29:30 minidsp-shd volumio[1594]: e is 65537 (0x010001) Jun 15 23:29:30 minidsp-shd volumio[1594]: writing RSA key Jun 15 23:29:30 minidsp-shd volumio[628]: error: MyVolumio Plugin failed to start in a timely fashion Jun 15 23:29:30 minidsp-shd volumio[628]: info: BOOT COMPLETED Jun 15 23:29:30 minidsp-shd volumio[628]: [Metrics] CommandRouter: 71s 771.77ms Jun 15 23:29:30 minidsp-shd volumio[628]: info: CoreCommandRouter::volumiosetStartupVolume Jun 15 23:29:30 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:30 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:30 minidsp-shd volumio[628]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:29:30 minidsp-shd volumio[628]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:29:31 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 15 23:29:31 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 15 23:29:31 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:31 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 15 23:29:31 minidsp-shd volumio[628]: info: Version has changed, forcing UI Reload Jun 15 23:29:31 minidsp-shd volumio[628]: info: CoreCommandRouter::Reload Ui Jun 15 23:29:32 minidsp-shd sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:29:32 minidsp-shd sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:32 minidsp-shd sudo[1635]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:32 minidsp-shd sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:32 minidsp-shd sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:32 minidsp-shd sudo[1638]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:32 minidsp-shd volumio[628]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.100 UA: Mozilla/5.0 (Linux; Android 15; SM-S926U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Total Clients: 9 Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 15 23:29:32 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 15 23:29:32 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:32 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:32 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:32 minidsp-shd volumio-remote-updater[448]: No test mode Jun 15 23:29:32 minidsp-shd volumio-remote-updater[448]: No alpha test mode Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:32 minidsp-shd volumio[628]: info: Retrieving Cloud Streaming UI Jun 15 23:29:32 minidsp-shd volumio[628]: info: Getting Tidal Cloud Configuration Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:32 minidsp-shd volumio[628]: info: Getting Qobuz Cloud Configuration Jun 15 23:29:32 minidsp-shd volumio[628]: info: Asking plugin for UI Config Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:32 minidsp-shd volumio[628]: info: Getting Spotify Cloud Configuration Jun 15 23:29:32 minidsp-shd volumio[628]: info: Asking plugin for UI Config Jun 15 23:29:32 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:32 minidsp-shd volumio[628]: info: Saving Spotify Acccount Jun 15 23:29:32 minidsp-shd volumio[628]: info: Got it Jun 15 23:29:32 minidsp-shd volumio[628]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 15 23:29:33 minidsp-shd volumio[628]: info: Got Tidal Cloud Configuration Jun 15 23:29:33 minidsp-shd volumio[628]: info: Got it Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:33 minidsp-shd volumio[628]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:33 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:33 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:33 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:34 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:35 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:35 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 15 23:29:35 minidsp-shd volumio[628]: info: MyVolumio login type: Token Jun 15 23:29:36 minidsp-shd volumio[628]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 15 23:29:36 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:29:36 minidsp-shd volumio[628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jun 15 23:29:36 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 15 23:29:36 minidsp-shd volumio[628]: info: MyVolumio login type: Token Jun 15 23:29:36 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:36 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:36 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 15 23:29:36 minidsp-shd volumio[628]: info: Loading i18n strings for locale en Jun 15 23:29:36 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 15 23:29:36 minidsp-shd volumio[628]: info: Setting timezone to Asia/Singapore Jun 15 23:29:37 minidsp-shd sudo[1647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 15 23:29:37 minidsp-shd sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:37 minidsp-shd sudo[1647]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:37 minidsp-shd sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Singapore /etc/localtime Jun 15 23:29:37 minidsp-shd sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:37 minidsp-shd sudo[1650]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:37 minidsp-shd sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 15 23:29:37 minidsp-shd sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:37 minidsp-shd sudo[1653]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:37 minidsp-shd sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Singapore Jun 15 23:29:37 minidsp-shd sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:37 minidsp-shd dbus-daemon[470]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.47' (uid=0 pid=1657 comm="/usr/bin/timedatectl set-timezone Asia/Singapore ") Jun 15 23:29:37 minidsp-shd systemd[1]: Starting Time & Date Service... Jun 15 23:29:37 minidsp-shd dbus-daemon[470]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 15 23:29:37 minidsp-shd systemd[1]: Started Time & Date Service. Jun 15 23:29:37 minidsp-shd sudo[1656]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:37 minidsp-shd volumio[628]: info: Fetching Streaming Services browse cache Jun 15 23:29:37 minidsp-shd volumio[628]: Updating browse sources language Jun 15 23:29:37 minidsp-shd volumio[628]: Cannot find translation for source Presets Jun 15 23:29:37 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:29:37 minidsp-shd volumio[628]: Cannot find translation for source Presets Jun 15 23:29:37 minidsp-shd volumio[628]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 15 23:29:37 minidsp-shd volumio[628]: info: MyVolumio token set successfully Jun 15 23:29:37 minidsp-shd volumio[628]: info: MYVOLUMIO: Adding device Jun 15 23:29:37 minidsp-shd volumio[628]: info: MYVOLUMIO: Evaluating Server Jun 15 23:29:38 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 15 23:29:38 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 15 23:29:38 minidsp-shd sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 15 23:29:38 minidsp-shd sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:38 minidsp-shd sudo[1662]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:38 minidsp-shd volumio[628]: info: MyVolumio status changed Jun 15 23:29:38 minidsp-shd volumio[628]: info: Streaming services startup Jun 15 23:29:38 minidsp-shd volumio[628]: info: Starting Streaming Daemon Jun 15 23:29:39 minidsp-shd volumio[628]: info: Removing browser output: myVolumio user plan is not superstar Jun 15 23:29:39 minidsp-shd volumio[628]: info: Removing audio output: Jun 15 23:29:39 minidsp-shd volumio[628]: info: Stoppping Tunnel 1 Jun 15 23:29:39 minidsp-shd sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 15 23:29:39 minidsp-shd sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:39 minidsp-shd sudo[1682]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:39 minidsp-shd sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 15 23:29:39 minidsp-shd sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:39 minidsp-shd sudo[1685]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:39 minidsp-shd volumio[628]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 15 23:29:39 minidsp-shd volumio[628]: wlan0 Interface doesn't support scanning. Jun 15 23:29:39 minidsp-shd volumio[628]: info: Cannot use regular scanning, forcing with ap-force Jun 15 23:29:39 minidsp-shd sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 15 23:29:39 minidsp-shd sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:39 minidsp-shd sudo[1688]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:39 minidsp-shd volumio[628]: command failed: No such device (-19) Jun 15 23:29:39 minidsp-shd volumio[628]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 15 23:29:39 minidsp-shd volumio[628]: command failed: No such device (-19) Jun 15 23:29:39 minidsp-shd volumio[628]: info: Setting Geolocation for MyVolumio to as1 Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:39 minidsp-shd volumio[628]: info: Remote SSH Stopped Jun 15 23:29:39 minidsp-shd volumio[628]: error: Cannot start Volumio Streaming Daemon Jun 15 23:29:39 minidsp-shd volumio[628]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 15 23:29:39 minidsp-shd volumio[628]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:39 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:40 minidsp-shd volumio[628]: info: Updating MyVolumio device info Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:40 minidsp-shd volumio[628]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:40 minidsp-shd volumio[628]: info: Retrieving Cloud Streaming UI Jun 15 23:29:40 minidsp-shd volumio[628]: info: Getting Tidal Cloud Configuration Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:40 minidsp-shd volumio[628]: info: Getting Qobuz Cloud Configuration Jun 15 23:29:40 minidsp-shd volumio[628]: info: Asking plugin for UI Config Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:40 minidsp-shd volumio[628]: info: Getting Spotify Cloud Configuration Jun 15 23:29:40 minidsp-shd volumio[628]: info: Asking plugin for UI Config Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:40 minidsp-shd volumio[628]: info: Saving Spotify Acccount Jun 15 23:29:40 minidsp-shd volumio[628]: info: Got it Jun 15 23:29:40 minidsp-shd volumio[628]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 15 23:29:40 minidsp-shd volumio[628]: info: Got Tidal Cloud Configuration Jun 15 23:29:40 minidsp-shd volumio[628]: info: Got it Jun 15 23:29:40 minidsp-shd volumio[628]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 23:29:40 minidsp-shd volumio[628]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetBrowseSources Jun 15 23:29:41 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:29:43 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jun 15 23:29:43 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 15 23:29:43 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:43 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jun 15 23:29:43 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jun 15 23:29:43 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 15 23:29:43 minidsp-shd volumio[628]: xcb_connection_has_error() returned true Jun 15 23:29:43 minidsp-shd nmbd[574]: [2025/06/15 23:29:43.352275, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 15 23:29:43 minidsp-shd nmbd[574]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 15 23:29:44 minidsp-shd volumio[628]: info: MYVOLUMIO: Adding device Jun 15 23:29:44 minidsp-shd volumio[628]: info: MYVOLUMIO: Evaluating Server Jun 15 23:29:45 minidsp-shd volumio[628]: info: Setting Geolocation for MyVolumio to as1 Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::Reload Ui Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jun 15 23:29:45 minidsp-shd volumio[628]: info: Wizard terminated Successfully Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::volumiosetStartupVolume Jun 15 23:29:45 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:29:45 minidsp-shd volumio[628]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 15 23:29:46 minidsp-shd sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:29:46 minidsp-shd sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:46 minidsp-shd sudo[1716]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:46 minidsp-shd sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:46 minidsp-shd sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:46 minidsp-shd sudo[1718]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:46 minidsp-shd volumio[628]: info: Updating MyVolumio device info Jun 15 23:29:46 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:46 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:46 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:29:46 minidsp-shd volumio[628]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.100 UA: Mozilla/5.0 (Linux; Android 15; SM-S926U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Total Clients: 10 Jun 15 23:29:46 minidsp-shd sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 15 23:29:46 minidsp-shd sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:46 minidsp-shd sudo[1724]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:46 minidsp-shd sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 15 23:29:46 minidsp-shd sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:29:46 minidsp-shd sudo[1726]: pam_unix(sudo:session): session closed for user root Jun 15 23:29:46 minidsp-shd volumio[628]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.100 UA: Mozilla/5.0 (Linux; Android 15; SM-S926U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Total Clients: 11 Jun 15 23:29:46 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 15 23:29:47 minidsp-shd volumio[628]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:47 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 23:29:47 minidsp-shd volumio[628]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 15 23:29:47 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:47 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:47 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:47 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:47 minidsp-shd volumio[628]: info: Listing playlists Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:47 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:47 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:47 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:48 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:48 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:48 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:29:48 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jun 15 23:29:50 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:50 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:29:50 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:29:50 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:29:50 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:29:50 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:29:50 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:29:50 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:29:53 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:29:53 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 23:30:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:30:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:30:03 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:30:03 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:30:03 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:30:03 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:30:05 minidsp-shd volumio[628]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 15 23:30:05 minidsp-shd volumio[628]: info: Received Get System Version Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:30:05 minidsp-shd volumio[628]: info: Received Get System Info Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:30:05 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:30:05 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:30:05 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 23:30:07 minidsp-shd volumio[628]: info: Discovery: Getting this device information Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:30:07 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 15 23:30:07 minidsp-shd systemd[1]: systemd-timedated.service: Succeeded. Jun 15 23:30:07 minidsp-shd volumio[628]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/minidspshd/buster/armhf Jun 15 23:30:07 minidsp-shd volumio-remote-updater[448]: No test mode Jun 15 23:30:07 minidsp-shd volumio-remote-updater[448]: No alpha test mode Jun 15 23:30:07 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 15 23:30:08 minidsp-shd volumio[628]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 15 23:30:08 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 15 23:30:10 minidsp-shd volumio[628]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/minidspshd/buster/armhf Jun 15 23:30:10 minidsp-shd volumio[628]: info: Folder /tmp/plugins removed Jun 15 23:30:12 minidsp-shd volumio[628]: info: Check plugin dependencies Jun 15 23:30:12 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 23:30:12 minidsp-shd volumio[628]: info: Checking if plugin already exists Jun 15 23:30:12 minidsp-shd volumio[628]: info: Rename folder Jun 15 23:30:12 minidsp-shd volumio[628]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 23:30:12 minidsp-shd volumio[628]: info: Move to category Jun 15 23:30:13 minidsp-shd volumio[628]: info: Checking if install.sh is present Jun 15 23:30:13 minidsp-shd volumio[628]: info: Executing install.sh Jun 15 23:30:13 minidsp-shd sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Jun 15 23:30:13 minidsp-shd sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:30:13 minidsp-shd volumio[628]: info: Installing Go-librespot Jun 15 23:30:13 minidsp-shd volumio[628]: info: Checking old vollibrespot installs Jun 15 23:30:13 minidsp-shd systemd[1]: Reloading. Jun 15 23:30:14 minidsp-shd systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 15 23:30:14 minidsp-shd volumio[628]: info: Downloading daemon Jun 15 23:30:18 minidsp-shd volumio[628]: info: Creating directories Jun 15 23:30:18 minidsp-shd volumio[628]: info: Creating data directory Jun 15 23:30:18 minidsp-shd volumio[628]: info: Creating Start Script Jun 15 23:30:18 minidsp-shd systemd[1]: Reloading. Jun 15 23:30:18 minidsp-shd systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 15 23:30:19 minidsp-shd sudo[1792]: pam_unix(sudo:session): session closed for user root Jun 15 23:30:19 minidsp-shd volumio[628]: Plugin install end detected on script Jun 15 23:30:19 minidsp-shd volumio[628]: info: Install script completed Jun 15 23:30:19 minidsp-shd volumio[628]: info: Adding reference to registry Jun 15 23:30:19 minidsp-shd volumio[628]: info: Done installing plugin. Jun 15 23:30:19 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 23:30:19 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 23:30:19 minidsp-shd volumio[628]: info: Folder /tmp/plugins removed Jun 15 23:30:19 minidsp-shd volumio[628]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 23:30:19 minidsp-shd volumio[628]: info: Folder /data/temp removed Jun 15 23:30:21 minidsp-shd volumio[628]: info: Enabling plugin spop Jun 15 23:30:21 minidsp-shd volumio[628]: info: Loading plugin "spop"... Jun 15 23:30:24 minidsp-shd volumio[628]: info: PLUGIN START: spop Jun 15 23:30:24 minidsp-shd volumio[628]: info: Creating Spotify config file Jun 15 23:30:24 minidsp-shd volumio[628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:30:24 minidsp-shd volumio[628]: info: Done. Jun 15 23:30:24 minidsp-shd volumio[628]: info: Spotify config file written Jun 15 23:30:24 minidsp-shd volumio[628]: info: No need to fix Spotify hosts Jun 15 23:30:24 minidsp-shd sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 15 23:30:24 minidsp-shd sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 23:30:24 minidsp-shd systemd[1]: Started go-librespot Daemon. Jun 15 23:30:24 minidsp-shd sudo[1840]: pam_unix(sudo:session): session closed for user root Jun 15 23:30:24 minidsp-shd go-librespot[1842]: go-librespot daemon starting... Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=info msg="running go-librespot 0.2.0" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=debug msg="no app state found" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=debug msg="stored credentials not found" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=info msg="generated new device id: f572d8849f6974e3b29c92c8519ebe1f8b6099d8" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 15 23:30:24 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:24+08:00" level=info msg="zeroconf server listening on port 33963" Jun 15 23:30:27 minidsp-shd volumio[628]: info: go-librespot daemon successfully initialized Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="obtained new client token: AADPon/CK08bq/BjKTZu9f/aky3olAVK1PUPYqvQpcCjbNGkolyTq5U1XAgLsyFEF4lMcgeX2n/IkPn4UtYAqfPHUfxP5I0a1k0IyCMWHhfKaE6oIIr3/Ug0HQGAhFBD7MwgWzSeKgyICab8DwnhslMMPJCXp6QJQFK+Zq8klgBQEERnt94UFVCcBg1dQ/6MDIRDF1xzkegagcYUfWg2lxoiPxD+mAJu5qjWJY6+7GbUzfySH+ZcBRs=" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="completed keyexchange" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="completed challenge" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=info msg="authenticated AP as 31dbmz3ae26wyzleu34o7zogfbbq" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=info msg="authenticated Login5 as 31dbmz3ae26wyzleu34o7zogfbbq" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=info msg="accepted zeroconf user 31dbmz3ae26wyzleu34o7zogfbbq from SM-S926U1" Jun 15 23:30:30 minidsp-shd volumio[628]: info: Initializing connection to go-librespot Websocket Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="new websocket client" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="dealer connection opened" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="starting accesspoint recv loop" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="starting dealer recv loop" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="received accesspoint ping" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="received connection id: MjJhZWExZjYtYWY0MC00ZDIyLWExMmQtYTI3MDc1M2QwNmFkK2RlYWxlcit0Y3A6Ly8wYWM5NDhlZC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMTdBMEJEMTM5RkI5RjRFREI4MkQ4MzQ1OUM5RUFCRDYyRjM3NUVGOThFOTI4MkFGREQxM0RFQkRDMDdCNEQ5Qw==" Jun 15 23:30:30 minidsp-shd volumio[628]: info: Connection to go-librespot Websocket established Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="received accesspoint pong ack" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="put connect state because NEW_DEVICE" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="update volume to 65535/65535" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="handling transfer player command from a3d240e3d757dfab3421bdbe6171c6c79b1ee09d" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="resolved context of track" uri="spotify:album:5Qguk7rtVSuc85OO7H3CEm" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="fetched new page 0 with 38 items (list: 38)" uri="spotify:album:5Qguk7rtVSuc85OO7H3CEm" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="shuffled context with seed 12825077670220534943 (len: 38, keep: 17)" uri="spotify:album:5Qguk7rtVSuc85OO7H3CEm" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="loading track (paused: true, position: 175631ms)" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=trace msg="emitting websocket event: will_play" Jun 15 23:30:30 minidsp-shd volumio[628]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7EmWxGbLI9kq4SCl8gokfb","play_origin":""}} Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1134" Jun 15 23:30:30 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 15 23:30:31 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:31+08:00" level=debug msg="selected format OGG_VORBIS_320 (79682e6093879185b446a8c0c991433ce490852e)" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:31 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:31+08:00" level=debug msg="requested aes key for file 79682e6093879185b446a8c0c991433ce490852e, gid: 7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched first chunk of 14, total size is 6865696 bytes" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched chunk 13/13, size: 49952" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="fetched chunk 12/13, size: 524288" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=trace msg="seek to 175631ms (diff: 32ms, samples: 7745327, bytes: 6372796)" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=debug msg="created new output device" Jun 15 23:30:32 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:32+08:00" level=info msg="loaded track \"Satie: 3 Gymnopédies: No. 1, Lent et douloureux\" (paused: true, position: 175631ms, duration: 189026ms, prefetched: false)" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="emitting websocket event: metadata" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="emitting websocket event: active" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="sending successful reply for dealer request" Jun 15 23:30:33 minidsp-shd volumio[628]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7EmWxGbLI9kq4SCl8gokfb","name":"Satie: 3 Gymnopédies: No. 1, Lent et douloureux","artist_names":["Erik Satie","Aldo Ciccolini"],"album_name":"Satie: Works for Piano","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022ab1c96d02525fe775be674d","position":175631,"duration":189026,"release_date":"year:2000 month:2 day:1","track_number":1,"disc_number":1}} Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 15 23:30:33 minidsp-shd volumio[628]: SPOTIFY: received: {"type":"active","data":null} Jun 15 23:30:33 minidsp-shd volumio[628]: info: Aligning Spotify Volume to Volumio Volume Jun 15 23:30:33 minidsp-shd volumio[628]: info: CoreCommandRouter::volumioGetState Jun 15 23:30:33 minidsp-shd volumio[628]: info: CorePlayQueue::getTrack 0 Jun 15 23:30:33 minidsp-shd volumio[628]: info: Setting Spotify Volume from Volumio: 73 Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="emitting websocket event: paused" Jun 15 23:30:33 minidsp-shd volumio[628]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7EmWxGbLI9kq4SCl8gokfb","play_origin":""}} Jun 15 23:30:33 minidsp-shd volumio[628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:30:33 minidsp-shd volumio[628]: TypeError: Cannot read property 'service' of undefined Jun 15 23:30:33 minidsp-shd volumio[628]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Jun 15 23:30:33 minidsp-shd volumio[628]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:274:18) Jun 15 23:30:33 minidsp-shd volumio[628]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Jun 15 23:30:33 minidsp-shd volumio[628]: at WebSocket.emit (events.js:400:28) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.emit (events.js:400:28) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jun 15 23:30:33 minidsp-shd volumio[628]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 15 23:30:33 minidsp-shd volumio[628]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jun 15 23:30:33 minidsp-shd volumio[628]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jun 15 23:30:33 minidsp-shd volumio[628]: at Socket.emit (events.js:400:28) Jun 15 23:30:33 minidsp-shd volumio[628]: at addChunk (internal/streams/readable.js:293:12) Jun 15 23:30:33 minidsp-shd volumio[628]: at readableAddChunk (internal/streams/readable.js:267:9) Jun 15 23:30:33 minidsp-shd volumio[628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="emitting websocket event: volume" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="handling resume player command from a3d240e3d757dfab3421bdbe6171c6c79b1ee09d" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="seek to 175631ms (diff: 32ms, samples: 7745327, bytes: 6372796)" uri="spotify:track:7EmWxGbLI9kq4SCl8gokfb" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 4096 Jun 15 23:30:33 minidsp-shd go-librespot[1842]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 4096 Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="resume track at 175599ms" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="sending successful reply for dealer request" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="prefetching next track" uri="spotify:track:5EU3vfzwpU4Ujzz7In82SL" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="selected format OGG_VORBIS_320 (bb170a46606ccf32af4a00cbf1316b29dd78e7ef)" uri="spotify:track:5EU3vfzwpU4Ujzz7In82SL" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="requested aes key for file bb170a46606ccf32af4a00cbf1316b29dd78e7ef, gid: 5EU3vfzwpU4Ujzz7In82SL" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: time="2025-06-15T23:30:33+08:00" level=trace msg="emitting websocket event: playing" Jun 15 23:30:33 minidsp-shd go-librespot[1842]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jun 15 23:30:33 minidsp-shd go-librespot[1842]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jun 15 23:30:34 minidsp-shd sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 23:29 Jun 15 23:30:34 minidsp-shd sudo[1860]: 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"