-- Logs begin at Thu 2019-02-14 14:11:59 +04, end at Sat 2024-10-26 23:21:55 +04. -- Oct 26 23:20:00 minidsp-shd volumio[20752]: Starting albumart workers Oct 26 23:20:00 minidsp-shd volumio[20752]: Starting albumart workers Oct 26 23:20:00 minidsp-shd volumio[20752]: Starting albumart workers Oct 26 23:20:01 minidsp-shd volumio[20752]: info: Applying required configuration parameters for plugin hi_res_audio Oct 26 23:20:01 minidsp-shd volumio[20752]: info: Loading plugin "inputs"... Oct 26 23:20:02 minidsp-shd volumio[20752]: info: Loading plugin "qobuz"... Oct 26 23:20:04 minidsp-shd volumio[20752]: info: Loading plugin "tidal"... Oct 26 23:20:06 minidsp-shd volumio[20752]: info: Loading plugin "oem_helper"... Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Applying required configuration parameters for plugin oem_helper Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Loading plugin "updater_comm"... Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Plugin mpdemulation is not enabled Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Loading plugin "rest_api"... Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Loading plugin "websocket"... Oct 26 23:20:07 minidsp-shd volumio[20752]: info: Loading i18n strings for locale en Oct 26 23:20:07 minidsp-shd volumio[20752]: Updating browse sources language Oct 26 23:20:07 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::initPlayerControls Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:20:08 minidsp-shd volumio[20752]: Express server listening on port 3000 Oct 26 23:20:08 minidsp-shd volumio[20752]: [Metrics] WebUI: 29s 314.97ms Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreStateMachine::resetVolumioState Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreStateMachine::getcurrentVolume Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:08 minidsp-shd sudo[20892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:20:08 minidsp-shd sudo[20892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:08 minidsp-shd sudo[20892]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Oct 26 23:20:08 minidsp-shd sudo[20895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:20:08 minidsp-shd sudo[20895]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:08 minidsp-shd volumio[20752]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 26 23:20:08 minidsp-shd volumio[20752]: info: Completed loading Core Plugins Oct 26 23:20:08 minidsp-shd sudo[20895]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:08 minidsp-shd volumio[20752]: info: Preparing to generate the ALSA configuration file Oct 26 23:20:08 minidsp-shd volumio[20752]: info: MRS: Removed streaming files Oct 26 23:20:08 minidsp-shd volumio[20752]: info: MRS: volumioStreaming STOPPED Oct 26 23:20:08 minidsp-shd volumio[20752]: info: MRS: SNAPSERVER STOPPED Oct 26 23:20:08 minidsp-shd volumio[20752]: info: MRS: SNAPCLIENT STOPPED Oct 26 23:20:08 minidsp-shd volumio[20752]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 26 23:20:08 minidsp-shd volumio[20752]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 26 23:20:08 minidsp-shd volumio[20752]: info: Reading ALSA contributions from plugins. Oct 26 23:20:08 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:08] [connect] Successful connection Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 1 Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 2 Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 4 Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Oct 26 23:20:08 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:20:08 minidsp-shd volumio[20752]: info: Discovery: Getting this device information Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:08 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:20:09 minidsp-shd ntpd[798]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Reloading queue from file Oct 26 23:20:09 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729970408 101 Oct 26 23:20:09 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreStateMachine::setRepeat null single undefined Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreStateMachine::setRandom null Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Setting Device type: NanoPi NEO 3 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Asound.conf file unchanged, so no further update is needed Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Output device has changed, restarting MPD Oct 26 23:20:09 minidsp-shd sudo[20904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 26 23:20:09 minidsp-shd sudo[20904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:09 minidsp-shd sudo[20904]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:09 minidsp-shd sudo[20907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 23:20:09 minidsp-shd sudo[20907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:09 minidsp-shd sudo[20907]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Output device has changed, restarting Shairport Sync Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:09 minidsp-shd sudo[20910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 23:20:09 minidsp-shd sudo[20910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:09 minidsp-shd systemd[1]: Stopping Music Player Daemon... Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:20:09 minidsp-shd volumio[20752]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 23:20:09 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 26 23:20:09 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:09 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 26 23:20:09 minidsp-shd volumio[20752]: info: ___________ START PLUGINS ___________ Oct 26 23:20:09 minidsp-shd volumio[20752]: info: ControllerMpd::onStart: Initializing MPD Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Creating MPD Configuration file Oct 26 23:20:09 minidsp-shd sudo[20919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 23:20:09 minidsp-shd sudo[20919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:09 minidsp-shd sudo[20919]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:09 minidsp-shd volumio[20752]: info: [1729970409827] CoreMusicLibrary::Adding element Media Servers Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:09 minidsp-shd sudo[20922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 23:20:09 minidsp-shd sudo[20922]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:20:09 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 26 23:20:09 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 26 23:20:09 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 26 23:20:09 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding Manifest REST API Endpoints Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding METAVOLUMIO REST API Endpoints Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:20:09 minidsp-shd volumio[20752]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:09 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:09 minidsp-shd sudo[20926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Oct 26 23:20:09 minidsp-shd sudo[20926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:10 minidsp-shd sudo[20926]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Preparing CD Folders Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Adding CD REST API Endpoints Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Starting UDEV Watcher for CD Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Detecting CD presence with UDEV Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:10 minidsp-shd volumio[20752]: info: [1729970410269] CoreMusicLibrary::Adding element Last_100 Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Starting RAAT Plugin Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Additional UI Settings Added for plugin music_service/raat Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Streaming services startup Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Starting Streaming Daemon Oct 26 23:20:10 minidsp-shd sudo[20935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 23:20:10 minidsp-shd sudo[20935]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:10 minidsp-shd sudo[20935]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:10 minidsp-shd volumio[20752]: info: [1729970410509] CoreMusicLibrary::Adding element Webradio Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Adding Calm Radio to Browse Sources Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:10 minidsp-shd volumio[20752]: info: [1729970410547] CoreMusicLibrary::Adding element Calm Radio Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:10 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:10 minidsp-shd sudo[20942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 23:20:10 minidsp-shd sudo[20942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:10 minidsp-shd volumio[20752]: info: [1729970410578] CoreMusicLibrary::Adding element YouTube Music Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:10 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:10 minidsp-shd volumio[20752]: Cannot find translation for source YouTube Music Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 26 23:20:10 minidsp-shd systemd[1]: Stopping Volumio Tidal Connect Service... Oct 26 23:20:10 minidsp-shd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 26 23:20:10 minidsp-shd systemd[1]: vtcs.service: Succeeded. Oct 26 23:20:10 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Oct 26 23:20:10 minidsp-shd volumio[20752]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 23:20:10 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:10 minidsp-shd sudo[20942]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:10 minidsp-shd volumio[20752]: error: Hi Res Audio Failed Login: Missing Login Data Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 26 23:20:10 minidsp-shd volumio[20752]: info: Refreshing QOBUZ token Oct 26 23:20:11 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:20:11 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Volumio Calling Home Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Stopping AccessToken refresher cron Oct 26 23:20:11 minidsp-shd volumio[20752]: info: AccessToken refresher cron started Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Adding TIDAL REST API Endpoints Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Discovery: adding c01b126f-5560-4637-90af-3370f12d9686 Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Discovery: Found device miniDSP SHD Oct 26 23:20:11 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:11 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:11 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output for this device Oct 26 23:20:11 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:11 minidsp-shd volumio[20752]: info: Serial port opened successfully Oct 26 23:20:11 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Oct 26 23:20:12 minidsp-shd volumio[20752]: error: Cannot start Volumio Streaming Daemon Oct 26 23:20:12 minidsp-shd volumio[20752]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 23:20:12 minidsp-shd volumio[20752]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 23:20:12 minidsp-shd volumio[20752]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Oct 26 23:20:12 minidsp-shd volumio[20752]: Can't get device info: No such device Oct 26 23:20:12 minidsp-shd volumio[20752]: info: MPD Permissions set Oct 26 23:20:12 minidsp-shd volumio[20752]: info: MPD Permissions set Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Upmpdcli Daemon Started Oct 26 23:20:12 minidsp-shd volumio[20752]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"disableVolumeControl":false} Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:12 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:12 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Ignoring ROON Volume update because of undefined data Oct 26 23:20:12 minidsp-shd volumio[20752]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Oct 26 23:20:12 minidsp-shd volumio[20752]: info: [msSurfaceDial init()] check /org/bluez Oct 26 23:20:12 minidsp-shd volumio[20752]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Oct 26 23:20:12 minidsp-shd volumio[20752]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::Reload Ui Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Volumio called home Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 23:20:12 minidsp-shd volumio[20752]: info: updateDSP function in raat called! Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Updating RAAT Signal Path Oct 26 23:20:12 minidsp-shd volumio[20752]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Reconfiguring and Restarting RAAT Plugin Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Not Reporting Auto name since its the default one Oct 26 23:20:12 minidsp-shd volumio[20752]: info: RAAT Overriding default device vendor model Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Adding MINIDSP Inputs Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:12 minidsp-shd volumio[20752]: info: [1729970412430] CoreMusicLibrary::Adding element Inputs Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:12 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:12 minidsp-shd volumio[20752]: Cannot find translation for source YouTube Music Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:12 minidsp-shd volumio[20752]: info: [1729970412444] CoreMusicLibrary::Adding element Presets Oct 26 23:20:12 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:12 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:12 minidsp-shd volumio[20752]: Cannot find translation for source YouTube Music Oct 26 23:20:12 minidsp-shd volumio[20752]: Cannot find translation for source Presets Oct 26 23:20:12 minidsp-shd volumio[20752]: (node:20752) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 26 23:20:12 minidsp-shd volumio[20752]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:12 minidsp-shd volumio[20752]: info: Adding audio output: Oct 26 23:20:13 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd sudo[20966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 26 23:20:13 minidsp-shd sudo[20966]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd sudo[20966]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 23:20:13 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Enabling external Volume Control Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:14 minidsp-shd volumio[20752]: info: TidalConnect service stoped! Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Volumio BT Module successfully started Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source YouTube Music Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source Presets Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Starting Shairport Sync Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Starting Shairport Sync Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Starting Shairport Sync Oct 26 23:20:14 minidsp-shd sudo[20985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:20:14 minidsp-shd sudo[20985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:14 minidsp-shd sudo[20987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:20:14 minidsp-shd sudo[20987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 26 23:20:14 minidsp-shd sudo[20991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:20:14 minidsp-shd sudo[20991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:14 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 23:20:14 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 26 23:20:14 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 23:20:14 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 23:20:14 minidsp-shd sudo[20987]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:14 minidsp-shd volumio[20752]: xcb_connection_has_error() returned true Oct 26 23:20:14 minidsp-shd sudo[20985]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:14 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 23:20:14 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 26 23:20:14 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 26 23:20:14 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 23:20:14 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 23:20:14 minidsp-shd sudo[20991]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Access Token successfully retrieved Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:20:14 minidsp-shd volumio[20752]: info: [1729970414824] CoreMusicLibrary::Adding element QOBUZ Oct 26 23:20:14 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:14 minidsp-shd sudo[20997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source Calm Radio Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source YouTube Music Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source Presets Oct 26 23:20:14 minidsp-shd volumio[20752]: Cannot find translation for source QOBUZ Oct 26 23:20:14 minidsp-shd sudo[20997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:14 minidsp-shd volumio[20752]: info: Stopping AccessToken refresher cron for QOBUZ Oct 26 23:20:14 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 26 23:20:14 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 26 23:20:14 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 26 23:20:14 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 26 23:20:14 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 26 23:20:14 minidsp-shd sudo[20997]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:15 minidsp-shd volumio[20752]: info: AccessToken refresher cron started for QOBUZ Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Adding QOBUZ REST API Endpoints Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 26 23:20:15 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:20:15 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:20:15 minidsp-shd mpd[20925]: Oct 26 23:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 23:20:15 minidsp-shd systemd[1]: Started Music Player Daemon. Oct 26 23:20:15 minidsp-shd sudo[20922]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:15 minidsp-shd sudo[20910]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Raat Daemon started successfully Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Shairport-Sync Started Oct 26 23:20:15 minidsp-shd volumio[20752]: Error adding Membership: Error: addMembership EINVAL Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Shairport-Sync Started Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Shairport-Sync Started Oct 26 23:20:15 minidsp-shd sudo[21019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 26 23:20:15 minidsp-shd sudo[21019]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:15 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Oct 26 23:20:15 minidsp-shd sudo[21019]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:15 minidsp-shd volumio[20752]: info: Executing endpoint restartRAATSocket Oct 26 23:20:15 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 26 23:20:15 minidsp-shd volumio[20752]: info: MRS: Getting audio outputs on start Oct 26 23:20:15 minidsp-shd volumio[20752]: info: MRS: Requesting all other devices output Oct 26 23:20:15 minidsp-shd volumio[20752]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 23:20:15 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:15 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:15 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:20:15 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:15 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:15 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:15 minidsp-shd volumio[20752]: error: Help! Some callbacks for volumioPushState are crashing! Oct 26 23:20:15 minidsp-shd volumio[20752]: error: Cannot read property 'sendVolumeMute' of undefined Oct 26 23:20:16 minidsp-shd volumio[20752]: error: MPD error: The expression evaluated to a falsy value: Oct 26 23:20:16 minidsp-shd volumio[20752]: assert.ok(self.idling) Oct 26 23:20:16 minidsp-shd volumio[20752]: error: The expression evaluated to a falsy value: Oct 26 23:20:16 minidsp-shd volumio[20752]: assert.ok(self.idling) Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: MPD running with PID20925 Oct 26 23:20:16 minidsp-shd volumio[20752]: ,establishing connection Oct 26 23:20:16 minidsp-shd volumio[20752]: error: updateQueue error: null Oct 26 23:20:16 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Oct 26 23:20:16 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Executing endpoint tc_getconfig Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 26 23:20:16 minidsp-shd vtcs[21021]: STARTING TidalConnect services, version: 1.3.0.19 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:16 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:16 minidsp-shd volumio[20752]: error: Help! Some callbacks for volumioPushState are crashing! Oct 26 23:20:16 minidsp-shd volumio[20752]: error: Cannot read property 'sendVolumeMute' of undefined Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 23:20:16 minidsp-shd volumio[20752]: error: updateQueue error: null Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Received Get System Version Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:20:16 minidsp-shd vtcs[21021]: STARTED TidalConnect services. Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Received Get System Info Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Discovery: Getting this device information Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetQueue Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreStateMachine::getQueue Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getQueue Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Listing playlists Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Received Get System Info Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:20:16 minidsp-shd volumio[20752]: info: Discovery: Getting this device information Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:16 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: Executing endpoint tc_connect Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 26 23:20:17 minidsp-shd volumio[20752]: info: Connecting to TidalConnect Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::servicePushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: 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} Oct 26 23:20:17 minidsp-shd volumio[20752]: verbose: CURRENT POSITION 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::syncState stateService stop Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::syncState currentStatus stop Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: No code Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::servicePushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: 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} Oct 26 23:20:17 minidsp-shd volumio[20752]: verbose: CURRENT POSITION 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::syncState stateService stop Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::syncState currentStatus stop Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: No code Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreStateMachine::pushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioPushState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:20:17 minidsp-shd volumio[20752]: info: MRS: Pushing multiroomSync output Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:17 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:18 minidsp-shd volumio[20752]: info: TidalConnect service started! Oct 26 23:20:18 minidsp-shd volumio[20752]: info: Completed starting Core Plugins Oct 26 23:20:18 minidsp-shd volumio[20752]: info: ------------------------------------------- Oct 26 23:20:18 minidsp-shd volumio[20752]: info: ----- MyVolumio plugins startup ---- Oct 26 23:20:18 minidsp-shd volumio[20752]: info: ------------------------------------------- Oct 26 23:20:18 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:20:20 minidsp-shd volumio[20752]: info: Not Reporting Auto name since its the default one Oct 26 23:20:20 minidsp-shd volumio[20752]: info: RAAT Overriding default device vendor model Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 26 23:20:20 minidsp-shd volumio[20752]: xcb_connection_has_error() returned true Oct 26 23:20:20 minidsp-shd sudo[21088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 26 23:20:20 minidsp-shd sudo[21088]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:20 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 26 23:20:20 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 26 23:20:20 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 26 23:20:20 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 26 23:20:20 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:20:20 minidsp-shd volumio[20752]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:20:20 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 26 23:20:20 minidsp-shd sudo[21088]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:20 minidsp-shd volumio[20752]: info: Raat Daemon started successfully Oct 26 23:20:20 minidsp-shd volumio[20752]: info: Executing endpoint restartRAATSocket Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 26 23:20:20 minidsp-shd volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 23:20:20 minidsp-shd volumio[20752]: info: updateDSP function in raat called! Oct 26 23:20:20 minidsp-shd volumio[20752]: info: Updating RAAT Signal Path Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CoreCommandRouter::volumioGetState Oct 26 23:20:20 minidsp-shd volumio[20752]: info: CorePlayQueue::getTrack 0 Oct 26 23:20:21 minidsp-shd volumio[20752]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 26 23:20:27 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 26 23:20:29 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 26 23:20:29 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:20:29 minidsp-shd volumio[20752]: info: Starting MyVolumio Remote Streaming Endpoints Oct 26 23:20:29 minidsp-shd volumio[20752]: info: MyVolumio login type: Token Oct 26 23:20:29 minidsp-shd volumio[20752]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 26 23:20:29 minidsp-shd volumio[20752]: 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' Oct 26 23:20:29 minidsp-shd volumio[20752]: Searching all installed plugins Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Oct 26 23:20:29 minidsp-shd volumio[20752]: info: [1729970429451] ControllerCalmRadio::searchCategories Oct 26 23:20:29 minidsp-shd volumio[20752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:20:29 minidsp-shd volumio[20752]: TypeError: Cannot read property 'cats' of undefined Oct 26 23:20:29 minidsp-shd volumio[20752]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Oct 26 23:20:29 minidsp-shd volumio[20752]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Oct 26 23:20:29 minidsp-shd volumio[20752]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Oct 26 23:20:29 minidsp-shd volumio[20752]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 26 23:20:29 minidsp-shd volumio[20752]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 26 23:20:29 minidsp-shd volumio[20752]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Oct 26 23:20:29 minidsp-shd volumio[20752]: at Socket.emit (events.js:400:28) Oct 26 23:20:29 minidsp-shd volumio[20752]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 26 23:20:29 minidsp-shd volumio[20752]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 26 23:20:29 minidsp-shd volumio[20752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:20:30 minidsp-shd sudo[21111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 23:19 Oct 26 23:20:30 minidsp-shd sudo[21111]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:30 minidsp-shd sudo[21111]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:30 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 26 23:20:30 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 26 23:20:30 minidsp-shd systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 26 23:20:30 minidsp-shd vtcs[21021]: [2024-10-26 23:20:30.758] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Oct 26 23:20:30 minidsp-shd systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 26 23:20:30 minidsp-shd systemd[1]: Started dynamicswap service. Oct 26 23:20:30 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Oct 26 23:20:31 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 26 23:20:31 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Oct 26 23:20:31 minidsp-shd systemd[1]: Started dynamicswap service. Oct 26 23:20:31 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Oct 26 23:20:31 minidsp-shd systemd[1]: Started Volumio Backend Module. Oct 26 23:20:31 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Oct 26 23:20:34 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:20:34 minidsp-shd volumio[21122]: info: ----- Volumio3 ---- Oct 26 23:20:34 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:20:34 minidsp-shd volumio[21122]: info: ----- System startup ---- Oct 26 23:20:34 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:20:35 minidsp-shd volumio[21122]: info: MYVOLUMIO Environment detected Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Plugin folders cleanup Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning into folder /volumio/app/plugins/ Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category audio_interface Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category miscellanea Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category music_service Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category plugins.json Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category system_controller Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category user_interface Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning into folder /data/plugins/ Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Scanning category music_service Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Plugin folders cleanup completed Oct 26 23:20:35 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:20:35 minidsp-shd volumio[21122]: info: ----- Core plugins startup ---- Oct 26 23:20:35 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Loading plugins from folder /volumio/app/plugins/ Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin bluetooth to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:35] [connect] Successful connection Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin multiroom to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin upnp to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin metavolumio to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin ms_surface_dial to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin cd_controller to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin raat to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Loading plugins from folder /data/plugins/ Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Loading plugin "system"... Oct 26 23:20:35 minidsp-shd volumio[21122]: info: Loading plugin "appearance"... Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Loading plugin "network"... Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Refreshing Cached IP Addresses Oct 26 23:20:37 minidsp-shd sudo[21149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:20:37 minidsp-shd sudo[21149]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Loading plugin "services"... Oct 26 23:20:37 minidsp-shd sudo[21151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:20:37 minidsp-shd sudo[21151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Loading plugin "alsa_controller"... Oct 26 23:20:37 minidsp-shd sudo[21149]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:37 minidsp-shd sudo[21151]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:37 minidsp-shd sudo[21154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 26 23:20:37 minidsp-shd sudo[21154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:37 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Loading plugin "wizard"... Oct 26 23:20:37 minidsp-shd volumio[21122]: info: Loading plugin "networkfs"... Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Starting Udev Watcher for removable devices Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Ignoring mount for partition: BOOT Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Ignoring mount for partition: volumio Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Ignoring mount for partition: volumio_data Oct 26 23:20:38 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Loading plugin "volumio_command_line_client"... Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Loading plugin "upnp"... Oct 26 23:20:38 minidsp-shd volumio[21122]: info: [1729970438133] Starting Upmpd Daemon Oct 26 23:20:38 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Loading plugin "my_music"... Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Loading plugin "mpd"... Oct 26 23:20:38 minidsp-shd volumio[21122]: info: Loading plugin "upnp_browser"... Oct 26 23:20:39 minidsp-shd volumio[21122]: info: Loading plugin "bluetooth"... Oct 26 23:20:39 minidsp-shd volumio[21122]: info: [1729970439632] Starting BluetoothController Oct 26 23:20:39 minidsp-shd volumio[21122]: info: Loading plugin "alarm-clock"... Oct 26 23:20:40 minidsp-shd volumio[21122]: info: Loading plugin "manifestui"... Oct 26 23:20:40 minidsp-shd volumio[21122]: info: Loading plugin "metavolumio"... Oct 26 23:20:42 minidsp-shd sudo[21154]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:42 minidsp-shd volumio[21122]: info: Loading plugin "airplay_emulation"... Oct 26 23:20:42 minidsp-shd volumio[21122]: info: Starting Shairport Sync Oct 26 23:20:42 minidsp-shd volumio[21122]: info: Loading plugin "cd_controller"... Oct 26 23:20:42 minidsp-shd volumio[21122]: info: Loading plugin "last_100"... Oct 26 23:20:42 minidsp-shd volumio[21122]: info: Loading plugin "raat"... Oct 26 23:20:43 minidsp-shd volumio[21122]: info: RAAT Plugin loaded Oct 26 23:20:43 minidsp-shd volumio[21122]: info: Adding restartRAATSocket REST API Endpoint Oct 26 23:20:43 minidsp-shd volumio[21122]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 26 23:20:43 minidsp-shd volumio[21122]: info: Loading plugin "streaming_services"... Oct 26 23:20:44 minidsp-shd volumio[21122]: info: Starting Streaming Service Transparent Proxy Oct 26 23:20:44 minidsp-shd volumio[21122]: info: Loading plugin "tidalconnect"... Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Loading plugin "webradio"... Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Loading plugin "i2s_dacs"... Oct 26 23:20:45 minidsp-shd volumio[21122]: info: I2S DAC not set, start Auto-detection Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Loading plugin "volumiodiscovery"... Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** For more information see Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:20:45 minidsp-shd volumio[21122]: *** WARNING *** For more information see Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** For more information see Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:20:45 minidsp-shd node[21122]: *** WARNING *** For more information see Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Discovery: Started advertising with name: miniDSP SHD Oct 26 23:20:45 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:20:45 minidsp-shd volumio[21122]: info: Loading plugin "calmradio"... Oct 26 23:20:47 minidsp-shd volumio[21122]: STREAMING PROXY: Starting server on port 3245 Oct 26 23:20:47 minidsp-shd volumio[21122]: Node JS runtime: 14 Oct 26 23:20:47 minidsp-shd volumio[21122]: info: Loading plugin "ytmusic"... Oct 26 23:20:48 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:20:48] [connect] Successful connection Oct 26 23:20:49 minidsp-shd volumio[21122]: info: Loading plugin "multiroom"... Oct 26 23:20:51 minidsp-shd volumio[21122]: info: Applying required configuration parameters for plugin multiroom Oct 26 23:20:51 minidsp-shd sudo[21172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 26 23:20:51 minidsp-shd sudo[21172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:51 minidsp-shd sudo[21172]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:51 minidsp-shd volumio[21122]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Oct 26 23:20:51 minidsp-shd volumio[21122]: info: MRS: MultiRoom plugin initialized Oct 26 23:20:51 minidsp-shd volumio[21122]: info: MRS: STOPPING SNAPCLIENT Oct 26 23:20:51 minidsp-shd volumio[21122]: info: MRS: Snap server stop Oct 26 23:20:51 minidsp-shd sudo[21188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 26 23:20:51 minidsp-shd sudo[21188]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:51 minidsp-shd volumio[21122]: info: MRS: STOPPING volumioStreaming Oct 26 23:20:51 minidsp-shd sudo[21191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 26 23:20:51 minidsp-shd sudo[21191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:51 minidsp-shd sudo[21188]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:51 minidsp-shd sudo[21194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 26 23:20:51 minidsp-shd sudo[21194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:51 minidsp-shd sudo[21191]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:51 minidsp-shd volumio[21122]: info: Loading plugin "outputs"... Oct 26 23:20:51 minidsp-shd volumio[21122]: info: Loading plugin "albumart"... Oct 26 23:20:51 minidsp-shd sudo[21197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 26 23:20:51 minidsp-shd sudo[21197]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:20:51 minidsp-shd sudo[21197]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:51 minidsp-shd sudo[21194]: pam_unix(sudo:session): session closed for user root Oct 26 23:20:51 minidsp-shd volumio[21122]: info: Loading plugin "ms_surface_dial"... Oct 26 23:20:52 minidsp-shd volumio[21122]: Forking 3 albumart workers Oct 26 23:20:52 minidsp-shd volumio[21122]: info: Applying required configuration parameters for plugin ms_surface_dial Oct 26 23:20:52 minidsp-shd volumio[21122]: info: Plugin example_plugin is not enabled Oct 26 23:20:52 minidsp-shd volumio[21122]: info: Loading plugin "hi_res_audio"... Oct 26 23:20:54 minidsp-shd volumio[21122]: Starting albumart workers Oct 26 23:20:54 minidsp-shd volumio[21122]: Starting albumart workers Oct 26 23:20:55 minidsp-shd volumio[21122]: Starting albumart workers Oct 26 23:20:55 minidsp-shd volumio[21122]: info: Applying required configuration parameters for plugin hi_res_audio Oct 26 23:20:55 minidsp-shd volumio[21122]: info: Loading plugin "inputs"... Oct 26 23:20:56 minidsp-shd volumio[21122]: info: Loading plugin "qobuz"... Oct 26 23:20:58 minidsp-shd volumio[21122]: info: Loading plugin "tidal"... Oct 26 23:21:00 minidsp-shd volumio[21122]: info: Loading plugin "oem_helper"... Oct 26 23:21:01 minidsp-shd volumio[21122]: info: Applying required configuration parameters for plugin oem_helper Oct 26 23:21:01 minidsp-shd volumio[21122]: info: Loading plugin "updater_comm"... Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Plugin mpdemulation is not enabled Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Loading plugin "rest_api"... Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Loading plugin "websocket"... Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Loading i18n strings for locale en Oct 26 23:21:02 minidsp-shd volumio[21122]: Updating browse sources language Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::initPlayerControls Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:02 minidsp-shd volumio[21122]: Express server listening on port 3000 Oct 26 23:21:02 minidsp-shd volumio[21122]: [Metrics] WebUI: 29s 349.04ms Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreStateMachine::resetVolumioState Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreStateMachine::getcurrentVolume Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:02 minidsp-shd sudo[21262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:21:02 minidsp-shd sudo[21262]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Oct 26 23:21:02 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Oct 26 23:21:02 minidsp-shd sudo[21262]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:02 minidsp-shd sudo[21264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:21:02 minidsp-shd sudo[21264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:02 minidsp-shd volumio[21122]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Completed loading Core Plugins Oct 26 23:21:02 minidsp-shd sudo[21264]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:02 minidsp-shd volumio[21122]: info: Preparing to generate the ALSA configuration file Oct 26 23:21:03 minidsp-shd volumio[21122]: info: MRS: Removed streaming files Oct 26 23:21:03 minidsp-shd volumio[21122]: info: MRS: volumioStreaming STOPPED Oct 26 23:21:03 minidsp-shd volumio[21122]: info: MRS: SNAPSERVER STOPPED Oct 26 23:21:03 minidsp-shd volumio[21122]: info: MRS: SNAPCLIENT STOPPED Oct 26 23:21:03 minidsp-shd volumio[21122]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 26 23:21:03 minidsp-shd volumio[21122]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 26 23:21:03 minidsp-shd volumio[21122]: info: Reading ALSA contributions from plugins. Oct 26 23:21:03 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:03] [connect] Successful connection Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 1 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 2 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 3 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 5 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 7 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 9 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 10 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 11 Oct 26 23:21:03 minidsp-shd volumio[21122]: info: Reloading queue from file Oct 26 23:21:03 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729970463 101 Oct 26 23:21:03 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 12 Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreStateMachine::setRepeat null single undefined Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreStateMachine::setRandom null Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:03 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:03 minidsp-shd volumio[21122]: info: Setting Device type: NanoPi NEO 3 Oct 26 23:21:04 minidsp-shd sudo[21273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 26 23:21:04 minidsp-shd sudo[21273]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd sudo[21273]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Discovery: adding c01b126f-5560-4637-90af-3370f12d9686 Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Discovery: Found device miniDSP SHD Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:04 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output for this device Oct 26 23:21:04 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Upmpdcli Daemon Started Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Asound.conf file unchanged, so no further update is needed Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Output device has changed, restarting MPD Oct 26 23:21:04 minidsp-shd sudo[21276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 23:21:04 minidsp-shd sudo[21276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd sudo[21276]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Output device has changed, restarting Shairport Sync Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:04 minidsp-shd sudo[21279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 23:21:04 minidsp-shd sudo[21279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd systemd[1]: Stopping Music Player Daemon... Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:21:04 minidsp-shd volumio[21122]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 23:21:04 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 26 23:21:04 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:04 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 26 23:21:04 minidsp-shd volumio[21122]: info: ___________ START PLUGINS ___________ Oct 26 23:21:04 minidsp-shd volumio[21122]: info: ControllerMpd::onStart: Initializing MPD Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Creating MPD Configuration file Oct 26 23:21:04 minidsp-shd sudo[21288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 23:21:04 minidsp-shd sudo[21288]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd sudo[21288]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:04 minidsp-shd volumio[21122]: info: [1729970464704] CoreMusicLibrary::Adding element Media Servers Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:04 minidsp-shd sudo[21291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 23:21:04 minidsp-shd sudo[21291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 26 23:21:04 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 26 23:21:04 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:21:04 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding Manifest REST API Endpoints Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding METAVOLUMIO REST API Endpoints Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:04 minidsp-shd sudo[21295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:04 minidsp-shd sudo[21295]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:04 minidsp-shd sudo[21295]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:04 minidsp-shd volumio[21122]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:04 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Preparing CD Folders Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Adding CD REST API Endpoints Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Starting UDEV Watcher for CD Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Detecting CD presence with UDEV Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:05 minidsp-shd volumio[21122]: info: [1729970465164] CoreMusicLibrary::Adding element Last_100 Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Starting RAAT Plugin Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Additional UI Settings Added for plugin music_service/raat Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Streaming services startup Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Starting Streaming Daemon Oct 26 23:21:05 minidsp-shd sudo[21304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 23:21:05 minidsp-shd sudo[21304]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:05 minidsp-shd sudo[21304]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:05 minidsp-shd volumio[21122]: info: [1729970465414] CoreMusicLibrary::Adding element Webradio Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Adding Calm Radio to Browse Sources Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:05 minidsp-shd volumio[21122]: info: [1729970465452] CoreMusicLibrary::Adding element Calm Radio Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:05 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:05 minidsp-shd sudo[21311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 23:21:05 minidsp-shd sudo[21311]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:05 minidsp-shd volumio[21122]: info: [1729970465483] CoreMusicLibrary::Adding element YouTube Music Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:05 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:05 minidsp-shd volumio[21122]: Cannot find translation for source YouTube Music Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 26 23:21:05 minidsp-shd volumio[21122]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:05 minidsp-shd systemd[1]: Stopping Volumio Tidal Connect Service... Oct 26 23:21:05 minidsp-shd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 26 23:21:05 minidsp-shd systemd[1]: vtcs.service: Succeeded. Oct 26 23:21:05 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Oct 26 23:21:05 minidsp-shd sudo[21311]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:05 minidsp-shd volumio[21122]: error: Hi Res Audio Failed Login: Missing Login Data Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Refreshing QOBUZ token Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:21:05 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:05 minidsp-shd volumio[21122]: info: Volumio Calling Home Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Stopping AccessToken refresher cron Oct 26 23:21:06 minidsp-shd volumio[21122]: info: AccessToken refresher cron started Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Adding TIDAL REST API Endpoints Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Serial port opened successfully Oct 26 23:21:06 minidsp-shd volumio[21122]: error: Cannot start Volumio Streaming Daemon Oct 26 23:21:06 minidsp-shd volumio[21122]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 23:21:06 minidsp-shd volumio[21122]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 23:21:06 minidsp-shd volumio[21122]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Oct 26 23:21:06 minidsp-shd volumio[21122]: Can't get device info: No such device Oct 26 23:21:06 minidsp-shd volumio[21122]: info: MPD Permissions set Oct 26 23:21:06 minidsp-shd volumio[21122]: info: MPD Permissions set Oct 26 23:21:06 minidsp-shd volumio[21122]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"disableVolumeControl":false} Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:06 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:06 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Ignoring ROON Volume update because of undefined data Oct 26 23:21:06 minidsp-shd volumio[21122]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Oct 26 23:21:06 minidsp-shd volumio[21122]: info: [msSurfaceDial init()] check /org/bluez Oct 26 23:21:06 minidsp-shd volumio[21122]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Oct 26 23:21:06 minidsp-shd volumio[21122]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::Reload Ui Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Received Get System Info Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Discovery: Getting this device information Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:06 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:06 minidsp-shd volumio[21122]: info: Listing playlists Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Volumio called home Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 23:21:07 minidsp-shd volumio[21122]: info: updateDSP function in raat called! Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Updating RAAT Signal Path Oct 26 23:21:07 minidsp-shd volumio[21122]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Reconfiguring and Restarting RAAT Plugin Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Not Reporting Auto name since its the default one Oct 26 23:21:07 minidsp-shd volumio[21122]: info: RAAT Overriding default device vendor model Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Adding MINIDSP Inputs Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:07 minidsp-shd volumio[21122]: info: [1729970467142] CoreMusicLibrary::Adding element Inputs Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:07 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:07 minidsp-shd volumio[21122]: Cannot find translation for source YouTube Music Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:07 minidsp-shd volumio[21122]: info: [1729970467155] CoreMusicLibrary::Adding element Presets Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:07 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:07 minidsp-shd volumio[21122]: Cannot find translation for source YouTube Music Oct 26 23:21:07 minidsp-shd volumio[21122]: Cannot find translation for source Presets Oct 26 23:21:07 minidsp-shd volumio[21122]: (node:21122) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 26 23:21:07 minidsp-shd volumio[21122]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:07 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:07 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd sudo[21335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 26 23:21:07 minidsp-shd sudo[21335]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:07 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd sudo[21335]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: Adding audio output: Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 26 23:21:08 minidsp-shd volumio[21122]: info: Enabling external Volume Control Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:08 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:09 minidsp-shd volumio[21122]: info: TidalConnect service stoped! Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Volumio BT Module successfully started Oct 26 23:21:09 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 26 23:21:09 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source YouTube Music Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source Presets Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Starting Shairport Sync Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Starting Shairport Sync Oct 26 23:21:09 minidsp-shd sudo[21354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:21:09 minidsp-shd sudo[21354]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Starting Shairport Sync Oct 26 23:21:09 minidsp-shd sudo[21357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:21:09 minidsp-shd sudo[21357]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:09 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 26 23:21:09 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 23:21:09 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 26 23:21:09 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 23:21:09 minidsp-shd sudo[21360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 23:21:09 minidsp-shd sudo[21360]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:09 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 23:21:09 minidsp-shd sudo[21354]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:09 minidsp-shd sudo[21357]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:09 minidsp-shd volumio[21122]: xcb_connection_has_error() returned true Oct 26 23:21:09 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 23:21:09 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 26 23:21:09 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 26 23:21:09 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 23:21:09 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 23:21:09 minidsp-shd sudo[21360]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:09 minidsp-shd sudo[21367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 26 23:21:09 minidsp-shd sudo[21367]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Access Token successfully retrieved Oct 26 23:21:09 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 23:21:09 minidsp-shd volumio[21122]: info: [1729970469725] CoreMusicLibrary::Adding element QOBUZ Oct 26 23:21:09 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source Calm Radio Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source YouTube Music Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source Presets Oct 26 23:21:09 minidsp-shd volumio[21122]: Cannot find translation for source QOBUZ Oct 26 23:21:09 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Stopping AccessToken refresher cron for QOBUZ Oct 26 23:21:09 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 26 23:21:09 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 26 23:21:09 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 26 23:21:09 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 26 23:21:09 minidsp-shd sudo[21367]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:09 minidsp-shd volumio[21122]: info: AccessToken refresher cron started for QOBUZ Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Adding QOBUZ REST API Endpoints Oct 26 23:21:09 minidsp-shd volumio[21122]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 26 23:21:10 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:21:10 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:21:10 minidsp-shd mpd[21293]: Oct 26 23:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 23:21:10 minidsp-shd systemd[1]: Started Music Player Daemon. Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Raat Daemon started successfully Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Shairport-Sync Started Oct 26 23:21:10 minidsp-shd sudo[21291]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:10 minidsp-shd sudo[21279]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:10 minidsp-shd volumio[21122]: Error adding Membership: Error: addMembership EINVAL Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Shairport-Sync Started Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Shairport-Sync Started Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Executing endpoint restartRAATSocket Oct 26 23:21:10 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 26 23:21:10 minidsp-shd volumio[21122]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 26 23:21:10 minidsp-shd volumio[21122]: info: MRS: Getting audio outputs on start Oct 26 23:21:10 minidsp-shd volumio[21122]: info: MRS: Requesting all other devices output Oct 26 23:21:10 minidsp-shd volumio[21122]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 23:21:10 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:10 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:10 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:10 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:10 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:10 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:10 minidsp-shd sudo[21391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 26 23:21:10 minidsp-shd sudo[21391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:10 minidsp-shd volumio[21122]: error: Help! Some callbacks for volumioPushState are crashing! Oct 26 23:21:10 minidsp-shd volumio[21122]: error: Cannot read property 'sendVolumeMute' of undefined Oct 26 23:21:10 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Oct 26 23:21:10 minidsp-shd sudo[21391]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:10 minidsp-shd volumio[21122]: info: MPD running with PID21293 Oct 26 23:21:10 minidsp-shd volumio[21122]: ,establishing connection Oct 26 23:21:11 minidsp-shd volumio[21122]: error: MPD error: The expression evaluated to a falsy value: Oct 26 23:21:11 minidsp-shd volumio[21122]: assert.ok(self.idling) Oct 26 23:21:11 minidsp-shd volumio[21122]: error: The expression evaluated to a falsy value: Oct 26 23:21:11 minidsp-shd volumio[21122]: assert.ok(self.idling) Oct 26 23:21:11 minidsp-shd volumio[21122]: error: MPD error: The expression evaluated to a falsy value: Oct 26 23:21:11 minidsp-shd volumio[21122]: assert.ok(self.idling) Oct 26 23:21:11 minidsp-shd volumio[21122]: error: The expression evaluated to a falsy value: Oct 26 23:21:11 minidsp-shd volumio[21122]: assert.ok(self.idling) Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: error: Help! Some callbacks for volumioPushState are crashing! Oct 26 23:21:11 minidsp-shd volumio[21122]: error: Cannot read property 'sendVolumeMute' of undefined Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 23:21:11 minidsp-shd volumio[21122]: error: updateQueue error: null Oct 26 23:21:11 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: Executing endpoint tc_getconfig Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 26 23:21:11 minidsp-shd vtcs[21393]: STARTING TidalConnect services, version: 1.3.0.19 Oct 26 23:21:11 minidsp-shd vtcs[21393]: STARTED TidalConnect services. Oct 26 23:21:11 minidsp-shd volumio[21122]: info: Executing endpoint tc_connect Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 26 23:21:11 minidsp-shd volumio[21122]: info: Connecting to TidalConnect Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::servicePushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: 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} Oct 26 23:21:11 minidsp-shd volumio[21122]: verbose: CURRENT POSITION 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::syncState stateService stop Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::syncState currentStatus stop Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: info: No code Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::servicePushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: 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} Oct 26 23:21:11 minidsp-shd volumio[21122]: verbose: CURRENT POSITION 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::syncState stateService stop Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::syncState currentStatus stop Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:11 minidsp-shd volumio[21122]: info: No code Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreStateMachine::pushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:11 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output update for this device Oct 26 23:21:11 minidsp-shd volumio[21122]: info: MRS: Pushing multiroomSync output Oct 26 23:21:12 minidsp-shd sudo[21412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:21:12 minidsp-shd sudo[21412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:12 minidsp-shd sudo[21412]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:12 minidsp-shd sudo[21415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:21:12 minidsp-shd sudo[21415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:12 minidsp-shd sudo[21415]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:12 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 15 Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 23:21:12 minidsp-shd volumio[21122]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Oct 26 23:21:12 minidsp-shd volumio[21122]: info: Received Get System Info Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:21:12 minidsp-shd volumio[21122]: info: Discovery: Getting this device information Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:12 minidsp-shd volumio[21122]: info: Listing playlists Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 23:21:12 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 23:21:13 minidsp-shd volumio[21122]: info: TidalConnect service started! Oct 26 23:21:13 minidsp-shd volumio[21122]: info: Completed starting Core Plugins Oct 26 23:21:13 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:21:13 minidsp-shd volumio[21122]: info: ----- MyVolumio plugins startup ---- Oct 26 23:21:13 minidsp-shd volumio[21122]: info: ------------------------------------------- Oct 26 23:21:13 minidsp-shd volumio[21122]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 23:21:14 minidsp-shd volumio[21122]: info: Received Get System Info Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:21:14 minidsp-shd volumio[21122]: info: Discovery: Getting this device information Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:21:14 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 26 23:21:15 minidsp-shd ntpd[798]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Not Reporting Auto name since its the default one Oct 26 23:21:15 minidsp-shd volumio[21122]: info: RAAT Overriding default device vendor model Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 26 23:21:15 minidsp-shd volumio[21122]: xcb_connection_has_error() returned true Oct 26 23:21:15 minidsp-shd sudo[21425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 26 23:21:15 minidsp-shd sudo[21425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:15 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 26 23:21:15 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 26 23:21:15 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 26 23:21:15 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 26 23:21:15 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 23:21:15 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 26 23:21:15 minidsp-shd volumio[21122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 23:21:15 minidsp-shd sudo[21425]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Raat Daemon started successfully Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Received Get System Info Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Discovery: Getting this device information Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Executing endpoint restartRAATSocket Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 23:21:15 minidsp-shd volumio[21122]: info: updateDSP function in raat called! Oct 26 23:21:15 minidsp-shd volumio[21122]: info: Updating RAAT Signal Path Oct 26 23:21:15 minidsp-shd volumio[21122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16 Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetState Oct 26 23:21:15 minidsp-shd volumio[21122]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:15 minidsp-shd volumio[21122]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 26 23:21:16 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 26 23:21:16 minidsp-shd volumio[21122]: info: CoreCommandRouter::volumioGetQueue Oct 26 23:21:16 minidsp-shd volumio[21122]: info: CoreStateMachine::getQueue Oct 26 23:21:16 minidsp-shd volumio[21122]: info: CorePlayQueue::getQueue Oct 26 23:21:19 minidsp-shd volumio[21122]: Searching all installed plugins Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Oct 26 23:21:19 minidsp-shd volumio[21122]: info: [1729970479638] ControllerCalmRadio::searchCategories Oct 26 23:21:19 minidsp-shd volumio[21122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:21:19 minidsp-shd volumio[21122]: TypeError: Cannot read property 'cats' of undefined Oct 26 23:21:19 minidsp-shd volumio[21122]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Oct 26 23:21:19 minidsp-shd volumio[21122]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Oct 26 23:21:19 minidsp-shd volumio[21122]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Oct 26 23:21:19 minidsp-shd volumio[21122]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 26 23:21:19 minidsp-shd volumio[21122]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 26 23:21:19 minidsp-shd volumio[21122]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Oct 26 23:21:19 minidsp-shd volumio[21122]: at Socket.emit (events.js:400:28) Oct 26 23:21:19 minidsp-shd volumio[21122]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 26 23:21:19 minidsp-shd volumio[21122]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 26 23:21:19 minidsp-shd volumio[21122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:21:20 minidsp-shd sudo[21486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 23:20 Oct 26 23:21:20 minidsp-shd sudo[21486]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:20 minidsp-shd sudo[21486]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:20 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 26 23:21:20 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 26 23:21:20 minidsp-shd vtcs[21393]: [2024-10-26 23:21:20.939] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Oct 26 23:21:20 minidsp-shd systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 26 23:21:20 minidsp-shd systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 26 23:21:21 minidsp-shd systemd[1]: Started dynamicswap service. Oct 26 23:21:21 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Oct 26 23:21:21 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 26 23:21:21 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Oct 26 23:21:21 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Oct 26 23:21:21 minidsp-shd systemd[1]: Started Volumio Backend Module. Oct 26 23:21:21 minidsp-shd systemd[1]: Started dynamicswap service. Oct 26 23:21:21 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Oct 26 23:21:24 minidsp-shd volumio[21496]: info: ------------------------------------------- Oct 26 23:21:24 minidsp-shd volumio[21496]: info: ----- Volumio3 ---- Oct 26 23:21:24 minidsp-shd volumio[21496]: info: ------------------------------------------- Oct 26 23:21:24 minidsp-shd volumio[21496]: info: ----- System startup ---- Oct 26 23:21:24 minidsp-shd volumio[21496]: info: ------------------------------------------- Oct 26 23:21:25 minidsp-shd volumio[21496]: info: MYVOLUMIO Environment detected Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Plugin folders cleanup Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning into folder /volumio/app/plugins/ Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category audio_interface Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category miscellanea Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category music_service Oct 26 23:21:25 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:25] [connect] Successful connection Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category plugins.json Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category system_controller Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category user_interface Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning into folder /data/plugins/ Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Scanning category music_service Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Plugin folders cleanup completed Oct 26 23:21:25 minidsp-shd volumio[21496]: info: ------------------------------------------- Oct 26 23:21:25 minidsp-shd volumio[21496]: info: ----- Core plugins startup ---- Oct 26 23:21:25 minidsp-shd volumio[21496]: info: ------------------------------------------- Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Loading plugins from folder /volumio/app/plugins/ Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Adding plugin bluetooth to MyMusic Plugins Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Adding plugin multiroom to MyMusic Plugins Oct 26 23:21:25 minidsp-shd volumio[21496]: info: Adding plugin upnp to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin metavolumio to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin ms_surface_dial to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin cd_controller to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin raat to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Loading plugins from folder /data/plugins/ Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Loading plugin "system"... Oct 26 23:21:26 minidsp-shd volumio[21496]: info: Loading plugin "appearance"... Oct 26 23:21:27 minidsp-shd volumio[21496]: info: Loading plugin "network"... Oct 26 23:21:27 minidsp-shd volumio[21496]: info: Refreshing Cached IP Addresses Oct 26 23:21:28 minidsp-shd sudo[21523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:21:28 minidsp-shd sudo[21523]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:28 minidsp-shd sudo[21523]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "services"... Oct 26 23:21:28 minidsp-shd sudo[21525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:21:28 minidsp-shd sudo[21525]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "alsa_controller"... Oct 26 23:21:28 minidsp-shd sudo[21525]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:28 minidsp-shd sudo[21528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 26 23:21:28 minidsp-shd sudo[21528]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:28 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "wizard"... Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "networkfs"... Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Starting Udev Watcher for removable devices Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Ignoring mount for partition: BOOT Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Ignoring mount for partition: volumio Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Ignoring mount for partition: volumio_data Oct 26 23:21:28 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "volumio_command_line_client"... Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "upnp"... Oct 26 23:21:28 minidsp-shd volumio[21496]: info: [1729970488363] Starting Upmpd Daemon Oct 26 23:21:28 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "my_music"... Oct 26 23:21:28 minidsp-shd volumio[21496]: info: Loading plugin "mpd"... Oct 26 23:21:29 minidsp-shd volumio[21496]: info: Loading plugin "upnp_browser"... Oct 26 23:21:29 minidsp-shd volumio[21496]: info: Loading plugin "bluetooth"... Oct 26 23:21:29 minidsp-shd volumio[21496]: info: [1729970489865] Starting BluetoothController Oct 26 23:21:30 minidsp-shd volumio[21496]: info: Loading plugin "alarm-clock"... Oct 26 23:21:30 minidsp-shd volumio[21496]: info: Loading plugin "manifestui"... Oct 26 23:21:30 minidsp-shd volumio[21496]: info: Loading plugin "metavolumio"... Oct 26 23:21:32 minidsp-shd sudo[21528]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:32 minidsp-shd volumio[21496]: info: Loading plugin "airplay_emulation"... Oct 26 23:21:32 minidsp-shd volumio[21496]: info: Starting Shairport Sync Oct 26 23:21:32 minidsp-shd volumio[21496]: info: Loading plugin "cd_controller"... Oct 26 23:21:32 minidsp-shd volumio[21496]: info: Loading plugin "last_100"... Oct 26 23:21:32 minidsp-shd volumio[21496]: info: Loading plugin "raat"... Oct 26 23:21:33 minidsp-shd volumio[21496]: info: RAAT Plugin loaded Oct 26 23:21:33 minidsp-shd volumio[21496]: info: Adding restartRAATSocket REST API Endpoint Oct 26 23:21:33 minidsp-shd volumio[21496]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 26 23:21:33 minidsp-shd volumio[21496]: info: Loading plugin "streaming_services"... Oct 26 23:21:34 minidsp-shd volumio[21496]: info: Starting Streaming Service Transparent Proxy Oct 26 23:21:34 minidsp-shd volumio[21496]: info: Loading plugin "tidalconnect"... Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Loading plugin "webradio"... Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Loading plugin "i2s_dacs"... Oct 26 23:21:35 minidsp-shd volumio[21496]: info: I2S DAC not set, start Auto-detection Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Loading plugin "volumiodiscovery"... Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** For more information see Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:21:35 minidsp-shd volumio[21496]: *** WARNING *** For more information see Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** For more information see Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 23:21:35 minidsp-shd node[21496]: *** WARNING *** For more information see Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Discovery: Started advertising with name: miniDSP SHD Oct 26 23:21:35 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 23:21:35 minidsp-shd volumio[21496]: info: Loading plugin "calmradio"... Oct 26 23:21:37 minidsp-shd volumio[21496]: STREAMING PROXY: Starting server on port 3245 Oct 26 23:21:37 minidsp-shd volumio[21496]: Node JS runtime: 14 Oct 26 23:21:37 minidsp-shd volumio[21496]: info: Loading plugin "ytmusic"... Oct 26 23:21:38 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:38] [connect] Successful connection Oct 26 23:21:39 minidsp-shd volumio[21496]: info: Loading plugin "multiroom"... Oct 26 23:21:41 minidsp-shd volumio[21496]: info: Applying required configuration parameters for plugin multiroom Oct 26 23:21:41 minidsp-shd sudo[21547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 26 23:21:41 minidsp-shd sudo[21547]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:41 minidsp-shd sudo[21547]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:42 minidsp-shd volumio[21496]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Oct 26 23:21:42 minidsp-shd volumio[21496]: info: MRS: MultiRoom plugin initialized Oct 26 23:21:42 minidsp-shd volumio[21496]: info: MRS: STOPPING SNAPCLIENT Oct 26 23:21:42 minidsp-shd volumio[21496]: info: MRS: Snap server stop Oct 26 23:21:42 minidsp-shd sudo[21563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 26 23:21:42 minidsp-shd volumio[21496]: info: MRS: STOPPING volumioStreaming Oct 26 23:21:42 minidsp-shd sudo[21563]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:42 minidsp-shd sudo[21565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 26 23:21:42 minidsp-shd sudo[21565]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:42 minidsp-shd sudo[21569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 26 23:21:42 minidsp-shd sudo[21563]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:42 minidsp-shd sudo[21569]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:42 minidsp-shd volumio[21496]: info: Loading plugin "outputs"... Oct 26 23:21:42 minidsp-shd sudo[21565]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:42 minidsp-shd sudo[21571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 26 23:21:42 minidsp-shd sudo[21571]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:42 minidsp-shd volumio[21496]: info: Loading plugin "albumart"... Oct 26 23:21:42 minidsp-shd sudo[21571]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:42 minidsp-shd sudo[21569]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:42 minidsp-shd volumio[21496]: info: Loading plugin "ms_surface_dial"... Oct 26 23:21:43 minidsp-shd volumio[21496]: Forking 3 albumart workers Oct 26 23:21:43 minidsp-shd volumio[21496]: info: Applying required configuration parameters for plugin ms_surface_dial Oct 26 23:21:43 minidsp-shd volumio[21496]: info: Plugin example_plugin is not enabled Oct 26 23:21:43 minidsp-shd volumio[21496]: info: Loading plugin "hi_res_audio"... Oct 26 23:21:45 minidsp-shd volumio[21496]: Starting albumart workers Oct 26 23:21:45 minidsp-shd volumio[21496]: Starting albumart workers Oct 26 23:21:45 minidsp-shd volumio[21496]: Starting albumart workers Oct 26 23:21:46 minidsp-shd volumio[21496]: info: Applying required configuration parameters for plugin hi_res_audio Oct 26 23:21:46 minidsp-shd volumio[21496]: info: Loading plugin "inputs"... Oct 26 23:21:47 minidsp-shd volumio[21496]: info: Loading plugin "qobuz"... Oct 26 23:21:49 minidsp-shd volumio[21496]: info: Loading plugin "tidal"... Oct 26 23:21:51 minidsp-shd volumio[21496]: info: Loading plugin "oem_helper"... Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Applying required configuration parameters for plugin oem_helper Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Loading plugin "updater_comm"... Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Plugin mpdemulation is not enabled Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Loading plugin "rest_api"... Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Loading plugin "websocket"... Oct 26 23:21:52 minidsp-shd volumio[21496]: info: Loading i18n strings for locale en Oct 26 23:21:52 minidsp-shd volumio[21496]: Updating browse sources language Oct 26 23:21:52 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::initPlayerControls Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 23:21:53 minidsp-shd volumio[21496]: Express server listening on port 3000 Oct 26 23:21:53 minidsp-shd volumio[21496]: [Metrics] WebUI: 29s 564.89ms Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreStateMachine::resetVolumioState Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreStateMachine::getcurrentVolume Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreStateMachine::pushState Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 23:21:53 minidsp-shd sudo[21636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 23:21:53 minidsp-shd sudo[21636]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:53 minidsp-shd sudo[21636]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Oct 26 23:21:53 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Oct 26 23:21:53 minidsp-shd sudo[21639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 23:21:53 minidsp-shd sudo[21639]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:53 minidsp-shd volumio[21496]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 26 23:21:53 minidsp-shd volumio[21496]: info: Completed loading Core Plugins Oct 26 23:21:53 minidsp-shd sudo[21639]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:53 minidsp-shd volumio[21496]: info: Preparing to generate the ALSA configuration file Oct 26 23:21:53 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:53] [connect] Successful connection Oct 26 23:21:53 minidsp-shd volumio[21496]: info: MRS: Removed streaming files Oct 26 23:21:53 minidsp-shd volumio[21496]: info: MRS: volumioStreaming STOPPED Oct 26 23:21:53 minidsp-shd volumio[21496]: info: MRS: SNAPSERVER STOPPED Oct 26 23:21:53 minidsp-shd volumio[21496]: info: MRS: SNAPCLIENT STOPPED Oct 26 23:21:53 minidsp-shd volumio[21496]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 26 23:21:53 minidsp-shd volumio[21496]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 26 23:21:53 minidsp-shd volumio[21496]: info: Reading ALSA contributions from plugins. Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 1 Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 2 Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 3 Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 4 Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 5 Oct 26 23:21:53 minidsp-shd volumio-remote-updater[451]: [2024-10-26 23:21:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729970513 101 Oct 26 23:21:53 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Oct 26 23:21:54 minidsp-shd volumio[21496]: info: Reloading queue from file Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreStateMachine::setRepeat null single undefined Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreStateMachine::pushState Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreStateMachine::setRandom null Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreStateMachine::pushState Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CorePlayQueue::getTrack 0 Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::volumioPushState Oct 26 23:21:54 minidsp-shd volumio[21496]: info: Setting Device type: NanoPi NEO 3 Oct 26 23:21:54 minidsp-shd volumio[21496]: verbose: New Socket.io Connection to 192.168.0.175 from 192.168.0.147 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/130.0.6723.37 Mobile/15E148 Safari/604.1 Total Clients: 7 Oct 26 23:21:54 minidsp-shd sudo[21647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 26 23:21:54 minidsp-shd sudo[21647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 23:21:54 minidsp-shd volumio[21496]: Searching all installed plugins Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: , search Oct 26 23:21:54 minidsp-shd volumio[21496]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 26 23:21:54 minidsp-shd volumio[21496]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:21:54 minidsp-shd sudo[21647]: pam_unix(sudo:session): session closed for user root Oct 26 23:21:54 minidsp-shd volumio[21496]: TypeError: Cannot read property 'then' of undefined Oct 26 23:21:54 minidsp-shd volumio[21496]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1582:17) Oct 26 23:21:54 minidsp-shd volumio[21496]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Oct 26 23:21:54 minidsp-shd volumio[21496]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 26 23:21:54 minidsp-shd volumio[21496]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 26 23:21:54 minidsp-shd volumio[21496]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Oct 26 23:21:54 minidsp-shd volumio[21496]: at Socket.emit (events.js:400:28) Oct 26 23:21:54 minidsp-shd volumio[21496]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 26 23:21:54 minidsp-shd volumio[21496]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 26 23:21:54 minidsp-shd volumio[21496]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 23:21:55 minidsp-shd sudo[21658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 23:20 Oct 26 23:21:55 minidsp-shd sudo[21658]: 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"