-- Logs begin at Mon 2025-06-09 15:03:03 IDT, end at Mon 2025-06-09 15:05:22 IDT. -- Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding Manifest REST API Endpoints Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jun 09 15:04:00 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 09 15:04:00 minidsp-shd systemd[1]: mpd.service: Succeeded. Jun 09 15:04:00 minidsp-shd systemd[1]: Stopped Music Player Daemon. Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding METAVOLUMIO REST API Endpoints Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:00 minidsp-shd systemd[1]: Starting Music Player Daemon... Jun 09 15:04:00 minidsp-shd volumio[635]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:00 minidsp-shd volumio[635]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 15:04:00 minidsp-shd volumio[635]: info: Preparing CD Folders Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding CD REST API Endpoints Jun 09 15:04:00 minidsp-shd volumio[635]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 09 15:04:00 minidsp-shd volumio[635]: info: Starting UDEV Watcher for CD Jun 09 15:04:00 minidsp-shd volumio[635]: info: Detecting CD presence with UDEV Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:00 minidsp-shd volumio[635]: info: [1749470640296] CoreMusicLibrary::Adding element Last_100 Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:00 minidsp-shd volumio[635]: info: Starting RAAT Plugin Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jun 09 15:04:00 minidsp-shd volumio[635]: info: Additional UI Settings Added for plugin music_service/raat Jun 09 15:04:00 minidsp-shd volumio[635]: info: Streaming services startup Jun 09 15:04:00 minidsp-shd volumio[635]: info: Starting Streaming Daemon Jun 09 15:04:00 minidsp-shd sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 09 15:04:00 minidsp-shd sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:00 minidsp-shd sudo[954]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:00 minidsp-shd volumio[635]: info: [1749470640584] CoreMusicLibrary::Adding element Webradio Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 15:04:00 minidsp-shd sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 09 15:04:00 minidsp-shd sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:00 minidsp-shd volumio[635]: info: Creating Spotify config file Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:00 minidsp-shd sudo[961]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:00 minidsp-shd volumio[635]: info: [squeezelite_mc] Starting proxy server... Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:00 minidsp-shd volumio[635]: info: [1749470640896] CoreMusicLibrary::Adding element YouTube Music Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:00 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 09 15:04:00 minidsp-shd volumio[635]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 09 15:04:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:01 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=635 comm="/usr/bin/node /volumio/index.js ") Jun 09 15:04:01 minidsp-shd volumio[635]: error: Hi Res Audio Failed Login: Missing Login Data Jun 09 15:04:01 minidsp-shd systemd[1]: Starting Bluetooth service... Jun 09 15:04:01 minidsp-shd volumio[635]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 09 15:04:01 minidsp-shd volumio[635]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 09 15:04:01 minidsp-shd volumio[635]: info: Refreshing QOBUZ token Jun 09 15:04:01 minidsp-shd volumio[635]: info: Refreshing TIDAL token Jun 09 15:04:01 minidsp-shd bluetoothd[967]: Bluetooth daemon 5.23 Jun 09 15:04:01 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.bluez' Jun 09 15:04:01 minidsp-shd systemd[1]: Started Bluetooth service. Jun 09 15:04:01 minidsp-shd bluetoothd[967]: Starting SDP server Jun 09 15:04:01 minidsp-shd bluetoothd[967]: kernel lacks bnep-protocol support Jun 09 15:04:01 minidsp-shd bluetoothd[967]: System does not support network plugin Jun 09 15:04:01 minidsp-shd bluetoothd[967]: Bluetooth management interface 1.14 initialized Jun 09 15:04:01 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=967 comm="/usr/local/libexec/bluetooth/bluetoothd ") Jun 09 15:04:01 minidsp-shd systemd[1]: Starting Hostname Service... Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:01 minidsp-shd volumio[635]: info: Volumio Calling Home Jun 09 15:04:01 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 09 15:04:01 minidsp-shd systemd[1]: Started Hostname Service. Jun 09 15:04:01 minidsp-shd volumio[635]: info: [squeezelite_mc] Proxy server started on port 35381 Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:01 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:01 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true} Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:01 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:01 minidsp-shd volumio[635]: info: [squeezelite_mc] Server discovery started Jun 09 15:04:01 minidsp-shd volumio[635]: info: [squeezelite_mc] Player finder started Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:01 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:01 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 09 15:04:02 minidsp-shd volumio[635]: info: Discovery: adding c7f941f6-5d36-485c-9931-666cac8a515f Jun 09 15:04:02 minidsp-shd volumio[635]: info: Discovery: Found device miniDSP SHD Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:02 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:02 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output for this device Jun 09 15:04:02 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:02 minidsp-shd volumio[635]: info: Adding audio output: Jun 09 15:04:02 minidsp-shd volumio[635]: info: Adding audio output: Jun 09 15:04:02 minidsp-shd volumio[635]: info: Serial port opened successfully Jun 09 15:04:02 minidsp-shd volumio[635]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["S16_LE","S24_LE"] Jun 09 15:04:02 minidsp-shd volumio[635]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"minidsp-shd","dsdFormat":"dop","card":"0","mixerType":"None","mixer":null} Jun 09 15:04:02 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 09 15:04:02 minidsp-shd volumio[635]: error: Cannot start Volumio Streaming Daemon Jun 09 15:04:02 minidsp-shd volumio[635]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 09 15:04:02 minidsp-shd volumio[635]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 09 15:04:02 minidsp-shd volumio[635]: info: RAAT Albumart path created successfully Jun 09 15:04:02 minidsp-shd volumio[635]: info: MPD Permissions set Jun 09 15:04:02 minidsp-shd volumio[635]: info: MPD Permissions set Jun 09 15:04:02 minidsp-shd volumio[635]: info: Upmpdcli Daemon Started Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:02 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:02 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"disableVolumeControl":false} Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:02 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:02 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:02 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jun 09 15:04:02 minidsp-shd volumio[635]: info: Ignoring ROON Volume update because of undefined data Jun 09 15:04:02 minidsp-shd volumio[635]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Jun 09 15:04:02 minidsp-shd volumio[635]: info: [msSurfaceDial init()] check /org/bluez Jun 09 15:04:02 minidsp-shd volumio[635]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Jun 09 15:04:02 minidsp-shd volumio[635]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::Reload Ui Jun 09 15:04:02 minidsp-shd sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 09 15:04:02 minidsp-shd sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:02 minidsp-shd volumio[635]: info: Volumio called home Jun 09 15:04:02 minidsp-shd volumio[635]: info: Spotify config file written Jun 09 15:04:02 minidsp-shd sudo[987]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jun 09 15:04:02 minidsp-shd volumio[635]: info: updateDSP function in raat called! Jun 09 15:04:02 minidsp-shd volumio[635]: info: Updating RAAT Signal Path Jun 09 15:04:02 minidsp-shd volumio[635]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Jun 09 15:04:02 minidsp-shd volumio[635]: info: Reconfiguring and Restarting RAAT Plugin Jun 09 15:04:02 minidsp-shd sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 09 15:04:02 minidsp-shd sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:02 minidsp-shd systemd[1]: Started go-librespot Daemon. Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd go-librespot[996]: go-librespot daemon starting... Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: Not Reporting Auto name since its the default one Jun 09 15:04:02 minidsp-shd sudo[990]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:02 minidsp-shd volumio[635]: info: RAAT Overriding default device vendor model Jun 09 15:04:02 minidsp-shd volumio[635]: info: Adding MINIDSP Inputs Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:02 minidsp-shd volumio[635]: info: [1749470642695] CoreMusicLibrary::Adding element Inputs Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:02 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:02 minidsp-shd volumio[635]: info: [1749470642706] CoreMusicLibrary::Adding element Presets Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:02 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:02 minidsp-shd volumio[635]: Cannot find translation for source Presets Jun 09 15:04:02 minidsp-shd volumio[635]: (node:635) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 09 15:04:02 minidsp-shd volumio[635]: (Use `node --trace-deprecation ...` to show where the warning was created) Jun 09 15:04:02 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:02 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:02+03:00" level=info msg="running go-librespot 0.2.0" Jun 09 15:04:02 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:02+03:00" level=debug msg="app state loaded" Jun 09 15:04:02 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:02+03:00" level=debug msg="stored credentials not found" Jun 09 15:04:03 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:03+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 09 15:04:03 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:03 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 09 15:04:03 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:03 minidsp-shd volumio[635]: info: No need to fix Spotify hosts Jun 09 15:04:03 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jun 09 15:04:03 minidsp-shd sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jun 09 15:04:03 minidsp-shd sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:03 minidsp-shd sudo[1018]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:03 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 192.168.1.173 from 192.168.1.181 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Jun 09 15:04:03 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 09 15:04:03 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:03+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 09 15:04:03 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:03+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 09 15:04:03 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:03+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 09 15:04:03 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:03+03:00" level=info msg="zeroconf server listening on port 36729" Jun 09 15:04:03 minidsp-shd volumio[635]: info: Starting Shairport Sync Jun 09 15:04:03 minidsp-shd sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 09 15:04:03 minidsp-shd sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:03 minidsp-shd volumio[635]: info: Starting Shairport Sync Jun 09 15:04:03 minidsp-shd sudo[1022]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:03 minidsp-shd sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 15:04:03 minidsp-shd volumio[635]: info: Starting Shairport Sync Jun 09 15:04:03 minidsp-shd sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:03 minidsp-shd sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 15:04:03 minidsp-shd sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:03 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 09 15:04:03 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 15:04:03 minidsp-shd sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 15:04:03 minidsp-shd sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:04 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 09 15:04:04 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 15:04:04 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 15:04:04 minidsp-shd sudo[1024]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:04 minidsp-shd sudo[1027]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:04 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 15:04:04 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 09 15:04:04 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Jun 09 15:04:04 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 15:04:04 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 15:04:04 minidsp-shd sudo[1031]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:04 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:04 minidsp-shd volumio[635]: Cannot find translation for source Presets Jun 09 15:04:04 minidsp-shd sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 09 15:04:04 minidsp-shd sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:04 minidsp-shd volumio[635]: info: Access Token successfully retrieved Jun 09 15:04:04 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 09 15:04:04 minidsp-shd sudo[1038]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:04 minidsp-shd volumio[635]: info: Access Token successfully retrieved Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:04 minidsp-shd volumio[635]: info: [1749470644331] CoreMusicLibrary::Adding element QOBUZ Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:04 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:04 minidsp-shd volumio[635]: Cannot find translation for source Presets Jun 09 15:04:04 minidsp-shd volumio[635]: Cannot find translation for source QOBUZ Jun 09 15:04:04 minidsp-shd volumio[635]: info: Stopping AccessToken refresher cron for QOBUZ Jun 09 15:04:04 minidsp-shd volumio[635]: info: AccessToken refresher cron started for QOBUZ Jun 09 15:04:04 minidsp-shd volumio[635]: info: Adding QOBUZ REST API Endpoints Jun 09 15:04:04 minidsp-shd volumio[635]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 09 15:04:04 minidsp-shd volumio[635]: info: TidalConnect service stoped! Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 09 15:04:04 minidsp-shd volumio[635]: info: Enabling external Volume Control Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:04 minidsp-shd volumio[635]: info: Raat Daemon started successfully Jun 09 15:04:04 minidsp-shd volumio[635]: info: Shairport-Sync Started Jun 09 15:04:04 minidsp-shd volumio[635]: Error adding Membership: Error: addMembership EINVAL Jun 09 15:04:04 minidsp-shd volumio[635]: info: Shairport-Sync Started Jun 09 15:04:04 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jun 09 15:04:05 minidsp-shd sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 09 15:04:05 minidsp-shd sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:05 minidsp-shd systemd[1]: Reloading. Jun 09 15:04:05 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Jun 09 15:04:05 minidsp-shd volumio-remote-updater[449]: [2025-06-09 15:04:05] [connect] Successful connection Jun 09 15:04:05 minidsp-shd volumio[635]: info: Executing endpoint restartRAATSocket Jun 09 15:04:05 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 09 15:04:05 minidsp-shd volumio[635]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 09 15:04:05 minidsp-shd volumio[635]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 09 15:04:05 minidsp-shd sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 09 15:04:05 minidsp-shd sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:05 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 09 15:04:05 minidsp-shd volumio[635]: info: Successfully retrieved User Session From TIDAL Jun 09 15:04:05 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:05 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:05 minidsp-shd volumio-remote-updater[449]: [2025-06-09 15:04:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749470645 101 Jun 09 15:04:05 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 13 Jun 09 15:04:06 minidsp-shd volumio[635]: info: MRS: Getting audio outputs on start Jun 09 15:04:06 minidsp-shd volumio[635]: info: MRS: Requesting all other devices output Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 15:04:06 minidsp-shd volumio[635]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:06 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetVisibleSources Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetQueue Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreStateMachine::getQueue Jun 09 15:04:06 minidsp-shd volumio[635]: info: CorePlayQueue::getQueue Jun 09 15:04:06 minidsp-shd volumio[635]: info: Listing playlists Jun 09 15:04:06 minidsp-shd systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 09 15:04:06 minidsp-shd systemd[1]: serial-getty@ttyS2.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 09 15:04:06 minidsp-shd volumio[635]: info: go-librespot daemon successfully initialized Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:06 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:06 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:06 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:06 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:06 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:06 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:06 minidsp-shd volumio[635]: error: Help! Some callbacks for volumioPushState are crashing! Jun 09 15:04:06 minidsp-shd volumio[635]: error: Cannot read property 'sendVolumeMute' of undefined Jun 09 15:04:06 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Jun 09 15:04:06 minidsp-shd sudo[1061]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:06 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Jun 09 15:04:06 minidsp-shd sudo[1078]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:07 minidsp-shd volumio[635]: info: Successfully retrieved User Subscription From TIDAL Jun 09 15:04:07 minidsp-shd volumio[635]: info: Adding TIDAL to Browse Sources Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 15:04:07 minidsp-shd volumio[635]: info: [1749470647027] CoreMusicLibrary::Adding element TIDAL Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 15:04:07 minidsp-shd volumio[635]: Cannot find translation for source YouTube Music Jun 09 15:04:07 minidsp-shd volumio[635]: Cannot find translation for source Presets Jun 09 15:04:07 minidsp-shd volumio[635]: Cannot find translation for source QOBUZ Jun 09 15:04:07 minidsp-shd volumio[635]: Cannot find translation for source TIDAL Jun 09 15:04:07 minidsp-shd volumio[635]: info: Stopping AccessToken refresher cron Jun 09 15:04:07 minidsp-shd volumio[635]: info: AccessToken refresher cron started Jun 09 15:04:07 minidsp-shd volumio[635]: info: Adding TIDAL REST API Endpoints Jun 09 15:04:07 minidsp-shd volumio[635]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:07 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:07 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:07 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:07 minidsp-shd volumio[635]: error: Help! Some callbacks for volumioPushState are crashing! Jun 09 15:04:07 minidsp-shd volumio[635]: error: Cannot read property 'sendVolumeMute' of undefined Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jun 09 15:04:07 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:07 minidsp-shd sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:07 minidsp-shd sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:07 minidsp-shd sudo[1119]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:07 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:07 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:07 minidsp-shd sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jun 09 15:04:07 minidsp-shd sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:07 minidsp-shd systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 09 15:04:07 minidsp-shd systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 09 15:04:07 minidsp-shd sudo[1122]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:07 minidsp-shd volumio[635]: info: Executing endpoint tc_getconfig Jun 09 15:04:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 09 15:04:07 minidsp-shd vtcs[1115]: STARTING TidalConnect services, version: 1.3.0.19 Jun 09 15:04:08 minidsp-shd volumio[635]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 09 15:04:08 minidsp-shd vtcs[1115]: STARTED TidalConnect services. Jun 09 15:04:08 minidsp-shd volumio[635]: info: Executing endpoint tc_connect Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 09 15:04:08 minidsp-shd volumio[635]: info: Connecting to TidalConnect Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:08 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:08 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:08 minidsp-shd volumio[635]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:08 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:08 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:08 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:08 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:08 minidsp-shd volumio[635]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 09 15:04:08 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:08 minidsp-shd sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:08 minidsp-shd sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:08 minidsp-shd sudo[1144]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:08 minidsp-shd mpd[944]: Jun 09 15:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 15:04:08 minidsp-shd systemd[1]: Started Music Player Daemon. Jun 09 15:04:08 minidsp-shd sudo[942]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:08 minidsp-shd volumio[635]: Upnp client error: Error: This socket has been ended by the other party Jun 09 15:04:08 minidsp-shd sudo[930]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:09 minidsp-shd volumio[635]: error: MPD error: The expression evaluated to a falsy value: Jun 09 15:04:09 minidsp-shd volumio[635]: assert.ok(self.idling) Jun 09 15:04:09 minidsp-shd volumio[635]: error: The expression evaluated to a falsy value: Jun 09 15:04:09 minidsp-shd volumio[635]: assert.ok(self.idling) Jun 09 15:04:09 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:09 minidsp-shd volumio[635]: error: updateQueue error: null Jun 09 15:04:09 minidsp-shd volumio[635]: info: MPD running with PID944 Jun 09 15:04:09 minidsp-shd volumio[635]: ,establishing connection Jun 09 15:04:09 minidsp-shd sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:09 minidsp-shd sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:09 minidsp-shd sudo[1151]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:09 minidsp-shd volumio[635]: error: updateQueue error: null Jun 09 15:04:09 minidsp-shd volumio[635]: info: Initializing connection to go-librespot Websocket Jun 09 15:04:09 minidsp-shd go-librespot[996]: time="2025-06-09T15:04:09+03:00" level=debug msg="new websocket client" Jun 09 15:04:09 minidsp-shd volumio[635]: info: Connection to go-librespot Websocket established Jun 09 15:04:09 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:09 minidsp-shd sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:09 minidsp-shd sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:09 minidsp-shd sudo[1155]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:09 minidsp-shd volumio[635]: info: TidalConnect service started! Jun 09 15:04:09 minidsp-shd volumio[928]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 15:04:10 minidsp-shd volumio[635]: info: Not Reporting Auto name since its the default one Jun 09 15:04:10 minidsp-shd volumio[635]: info: RAAT Overriding default device vendor model Jun 09 15:04:10 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 09 15:04:10 minidsp-shd sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:10 minidsp-shd sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:10 minidsp-shd sudo[1177]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:10 minidsp-shd sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 09 15:04:10 minidsp-shd sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:10 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Jun 09 15:04:10 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 09 15:04:10 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Jun 09 15:04:10 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Jun 09 15:04:10 minidsp-shd systemd[1]: Started RAAT DAEMON. Jun 09 15:04:10 minidsp-shd sudo[1182]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:10 minidsp-shd volumio[635]: info: Raat Daemon started successfully Jun 09 15:04:10 minidsp-shd volumio[635]: info: Executing endpoint restartRAATSocket Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 09 15:04:10 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Jun 09 15:04:10 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:10 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Jun 09 15:04:11 minidsp-shd volumio[635]: info: updateDSP function in raat called! Jun 09 15:04:11 minidsp-shd volumio[635]: info: Updating RAAT Signal Path Jun 09 15:04:11 minidsp-shd volumio[635]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 15:04:11 minidsp-shd sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 15:04:11 minidsp-shd sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:11 minidsp-shd sudo[1196]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:11 minidsp-shd volumio[635]: info: Completed starting Core Plugins Jun 09 15:04:11 minidsp-shd volumio[635]: info: ------------------------------------------- Jun 09 15:04:11 minidsp-shd volumio[635]: info: ----- MyVolumio plugins startup ---- Jun 09 15:04:11 minidsp-shd volumio[635]: info: ------------------------------------------- Jun 09 15:04:11 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 09 15:04:12 minidsp-shd volumio[635]: info: Getting Spotify volume Jun 09 15:04:12 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Jun 09 15:04:12 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:12 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:12 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:12 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME undefined Jun 09 15:04:12 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 70 Jun 09 15:04:12 minidsp-shd volumio[635]: info: Aligning Spotify Volume to Volumio Volume Jun 09 15:04:12 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:12 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 0 Jun 09 15:04:12 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 70 Jun 09 15:04:13 minidsp-shd volumio[635]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 09 15:04:14 minidsp-shd volumio[635]: SPOTIFY: SETTING SPOTIFY VOLUME 70 Jun 09 15:04:14 minidsp-shd volumio[635]: info: Sending Spotify command with payload to local API: /player/volume Jun 09 15:04:14 minidsp-shd volumio[928]: ...............................................................++++ Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 09 15:04:19 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 09 15:04:21 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 09 15:04:21 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 09 15:04:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:21 minidsp-shd volumio[635]: info: Starting MyVolumio Remote Streaming Endpoints Jun 09 15:04:21 minidsp-shd volumio[635]: info: MyVolumio login type: Token Jun 09 15:04:22 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 09 15:04:22 minidsp-shd volumio[635]: 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 09 15:04:22 minidsp-shd volumio[635]: error: MyVolumio Custom Token format not valid, refreshing it Jun 09 15:04:23 minidsp-shd volumio[635]: info: MyVolumio login type: Token Jun 09 15:04:24 minidsp-shd volumio[635]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPlay Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::play index 60 Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::stop Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::play index undefined Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 15:04:24 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::startPlaybackTimer Jun 09 15:04:24 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:24 minidsp-shd volumio[635]: info: [1749470664339] ControllerTidal::clearAddPlayTrack Jun 09 15:04:24 minidsp-shd volumio[635]: info: Getting stream with soundQuality LOSSLESS Jun 09 15:04:24 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 15:04:24 minidsp-shd volumio[635]: info: sendMpdCommand stop took 8 milliseconds Jun 09 15:04:24 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 15:04:24 minidsp-shd volumio[635]: info: sendMpdCommand clear took 3 milliseconds Jun 09 15:04:24 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS" Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS" took 11 milliseconds Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 15:04:24 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand play Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 43ms Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 39ms Jun 09 15:04:24 minidsp-shd volumio[635]: info: sendMpdCommand play took 34 milliseconds Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 33ms Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 28ms Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Jun 09 15:04:24 minidsp-shd volumio[635]: ---------------------------- MPD announces system playlist update Jun 09 15:04:24 minidsp-shd volumio[635]: info: Ignoring MPD Status Update Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 15ms Jun 09 15:04:24 minidsp-shd volumio[635]: info: ------------------------------ 12ms Jun 09 15:04:24 minidsp-shd volumio[635]: STREAMING PROXY: Handling url /?data=tidal://song/92858533&soundQuality=LOSSLESS Jun 09 15:04:24 minidsp-shd volumio[635]: info: Executing endpoint getStreamUrltidal Jun 09 15:04:24 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 09 15:04:24 minidsp-shd volumio[635]: info: MyVolumio token set successfully Jun 09 15:04:24 minidsp-shd volumio[635]: info: MYVOLUMIO: Adding device Jun 09 15:04:24 minidsp-shd volumio[635]: info: MYVOLUMIO: Evaluating Server Jun 09 15:04:25 minidsp-shd volumio[635]: info: MyVolumio status changed Jun 09 15:04:25 minidsp-shd volumio[635]: info: Streaming services startup Jun 09 15:04:25 minidsp-shd volumio[635]: info: Starting Streaming Daemon Jun 09 15:04:25 minidsp-shd volumio[635]: info: Removing browser output: myVolumio user plan is not superstar Jun 09 15:04:25 minidsp-shd volumio[635]: info: Removing audio output: Jun 09 15:04:25 minidsp-shd volumio[635]: info: Stoppping Tunnel 1 Jun 09 15:04:25 minidsp-shd sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 09 15:04:25 minidsp-shd sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:25 minidsp-shd sudo[1225]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:25 minidsp-shd volumio[635]: info: getStreamUrl took 1260 milliseconds Jun 09 15:04:25 minidsp-shd sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 09 15:04:25 minidsp-shd sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:04:25 minidsp-shd volumio[635]: error: Cannot start Volumio Streaming Daemon Jun 09 15:04:25 minidsp-shd volumio[635]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 09 15:04:25 minidsp-shd volumio[635]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 09 15:04:25 minidsp-shd volumio[635]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic5OTg2MDJjYTk2NjU2Mjg3MGE1ZTUyOGJmMWVlNjI5Y182MS5tcDQ/0.flac?token=1749473970~NjA3NGVjN2I0N2I3NzYyYjkwYjk1NTMwMTkyNjUzOTVhNzA1MWNhYw== Jun 09 15:04:25 minidsp-shd sudo[1228]: pam_unix(sudo:session): session closed for user root Jun 09 15:04:26 minidsp-shd volumio[635]: info: Remote SSH Stopped Jun 09 15:04:26 minidsp-shd volumio[635]: info: Setting Geolocation for MyVolumio to eu7 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:26 minidsp-shd volumio[635]: STREAMING PROXY: Response: 200, length: 35442791 Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 23 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 20 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 18 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: Jun 09 15:04:26 minidsp-shd volumio[635]: ---------------------------- MPD announces state update: player Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::getState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand status Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 45 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 30 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand status took 29 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseTrackInfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseTrackInfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseTrackInfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseState Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus stop Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus play Jun 09 15:04:26 minidsp-shd volumio[635]: info: Received an update from plugin. extracting info from payload Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus play Jun 09 15:04:26 minidsp-shd volumio[635]: info: Received an update from plugin. extracting info from payload Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 358ms Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 402ms Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 400ms Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 327 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 319 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: info: sendMpdCommand playlistinfo took 318 milliseconds Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseTrackInfo Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: ControllerMpd::parseTrackInfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1043 Kbps","isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus play Jun 09 15:04:26 minidsp-shd volumio[635]: info: Received an update from plugin. extracting info from payload Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus play Jun 09 15:04:26 minidsp-shd volumio[635]: info: Received an update from plugin. extracting info from payload Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: ControllerMpd::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CorePlayQueue::getTrack 60 Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"92858533&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92858533&soundQuality=LOSSLESS","trackType":"tidal"} Jun 09 15:04:26 minidsp-shd volumio[635]: verbose: CURRENT POSITION 60 Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState stateService play Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::syncState currentStatus play Jun 09 15:04:26 minidsp-shd volumio[635]: info: Received an update from plugin. extracting info from payload Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:26 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 652ms Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 624ms Jun 09 15:04:26 minidsp-shd volumio[635]: info: ------------------------------ 623ms Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: info: Updating MyVolumio device info Jun 09 15:04:27 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:27 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:27 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: info: Successfully Added MyVolumio device Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:27 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:28 minidsp-shd volumio[635]: info: Successfully Updated MyVolumio device Jun 09 15:04:28 minidsp-shd volumio[635]: STREAMING PROXY: Client dropped request, destroying Jun 09 15:04:31 minidsp-shd volumio[635]: error: MyVolumio Plugin failed to start in a timely fashion Jun 09 15:04:31 minidsp-shd volumio[635]: info: BOOT COMPLETED Jun 09 15:04:31 minidsp-shd volumio[635]: [Metrics] CommandRouter: 76s 522.26ms Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetStartupVolume Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:31 minidsp-shd volumio[635]: info: VolumeController:: Setting startup Volume 100 Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::Close All Modals sent Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::Close All Modals sent Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:31 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"mute":false,"disableVolumeControl":false} Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:31 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:31 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:31 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 09 15:04:31 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME 70 Jun 09 15:04:31 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 100 Jun 09 15:04:31 minidsp-shd volumio[635]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 09 15:04:31 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 100 Jun 09 15:04:31 minidsp-shd volumio[635]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 09 15:04:31 minidsp-shd volumio[635]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Jun 09 15:04:31 minidsp-shd volumio[635]: aplay: main:828: audio open error: Device or resource busy Jun 09 15:04:31 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 15:04:31 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 09 15:04:31 minidsp-shd volumio-remote-updater[449]: No test mode Jun 09 15:04:32 minidsp-shd volumio-remote-updater[449]: No alpha test mode Jun 09 15:04:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 09 15:04:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 09 15:04:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 15:04:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 09 15:04:33 minidsp-shd volumio[635]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jun 09 15:04:33 minidsp-shd volumio[635]: info: Sending Spotify command with payload to local API: /player/volume Jun 09 15:04:33 minidsp-shd volumio[635]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 09 15:04:33 minidsp-shd volumio[928]: .............................................................................................................................................................................................................................................................++++ Jun 09 15:04:33 minidsp-shd volumio[928]: e is 65537 (0x010001) Jun 09 15:04:33 minidsp-shd volumio[928]: writing RSA key Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:33 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:33 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME 100 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 70 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 09 15:04:34 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 70 Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:34 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":70,"dbVolume":-38,"mute":false,"disableVolumeControl":false} Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:34 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":50,"dbVolume":-63.5,"mute":false,"disableVolumeControl":false} Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:04:34 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:04:34 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME 70 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 50 Jun 09 15:04:34 minidsp-shd volumio[635]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 09 15:04:34 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 50 Jun 09 15:04:35 minidsp-shd volumio[635]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Jun 09 15:04:35 minidsp-shd volumio[635]: info: Sending Spotify command with payload to local API: /player/volume Jun 09 15:04:36 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 09 15:04:36 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16 Jun 09 15:04:37 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetBrowseSources Jun 09 15:04:41 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 15:05:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: Setting this device as Streaming Server Jun 09 15:05:00 minidsp-shd volumio[635]: info: Jun 09 15:05:00 minidsp-shd volumio[635]: [1749470700028] ---------------------------- MRS: Setting Streaming Server Jun 09 15:05:00 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: enable multiroom server output Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: Set multiroom target PCM to volumioMultiRoom Jun 09 15:05:00 minidsp-shd volumio[635]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Jun 09 15:05:00 minidsp-shd volumio[635]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: STARTING volumioStreaming Jun 09 15:05:00 minidsp-shd sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 09 15:05:00 minidsp-shd sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:05:00 minidsp-shd sudo[1333]: pam_unix(sudo:session): session closed for user root Jun 09 15:05:00 minidsp-shd sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Jun 09 15:05:00 minidsp-shd sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:05:00 minidsp-shd systemd[1]: Started VolumioStreamingService. Jun 09 15:05:00 minidsp-shd sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: volumioStreaming STARTED Jun 09 15:05:00 minidsp-shd sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 09 15:05:00 minidsp-shd sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 15:05:00 minidsp-shd sudo[1339]: pam_unix(sudo:session): session closed for user root Jun 09 15:05:00 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:00 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:00 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:02 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:02 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:02 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:05:07 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":45,"dbVolume":-70,"mute":false,"disableVolumeControl":false} Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:05:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:05:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME 50 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 45 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 09 15:05:07 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 45 Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Jun 09 15:05:07 minidsp-shd volumio[635]: info: msSurfaceDial volumioupdatevolume callback: {"vol":40,"dbVolume":-76.5,"mute":false,"disableVolumeControl":false} Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 15:05:07 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Jun 09 15:05:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Jun 09 15:05:07 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME 45 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 40 Jun 09 15:05:07 minidsp-shd volumio[635]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 09 15:05:07 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 40 Jun 09 15:05:09 minidsp-shd volumio[635]: SPOTIFY: SETTING SPOTIFY VOLUME 40 Jun 09 15:05:09 minidsp-shd volumio[635]: info: Sending Spotify command with payload to local API: /player/volume Jun 09 15:05:12 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:12 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:12 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:13 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:13 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:13 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:13 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:13 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:13 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:14 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:14 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:14 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:15 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:15 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jun 09 15:05:15 minidsp-shd volumio[635]: info: MRS: Starting browser stream Jun 09 15:05:15 minidsp-shd volumio[635]: info: Enabled audio output: browserPlayback Jun 09 15:05:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jun 09 15:05:21 minidsp-shd volumio[635]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jun 09 15:05:21 minidsp-shd volumio[635]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 15:05:21 minidsp-shd volumio[635]: TypeError: Cannot read property 'then' of undefined Jun 09 15:05:21 minidsp-shd volumio[635]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jun 09 15:05:21 minidsp-shd volumio[635]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2249:30) Jun 09 15:05:21 minidsp-shd volumio[635]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Jun 09 15:05:21 minidsp-shd volumio[635]: at Socket.emit (events.js:400:28) Jun 09 15:05:21 minidsp-shd volumio[635]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 09 15:05:21 minidsp-shd volumio[635]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 09 15:05:21 minidsp-shd volumio[635]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 15:05:22 minidsp-shd sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 15:04 Jun 09 15:05:22 minidsp-shd sudo[1435]: 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"