Apr 25 18:41:01 volumio sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 18:41:01 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:01 volumio sudo[2302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 18:41:01 volumio sudo[2302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:01 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:01 volumio sudo[2302]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:01 volumio volumio[1027]: verbose: New Socket.io Connection to 192.168.178.23 from 192.168.178.21 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 25 18:41:01 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 25 18:41:01 volumio volumio[1027]: info: Received Get System Info Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:01 volumio volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:01 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:01 volumio volumio-remote-updater[697]: No test mode Apr 25 18:41:01 volumio volumio-remote-updater[697]: No alpha test mode Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:01 volumio volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:41:01 volumio volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:01 volumio volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:41:01 volumio volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:01 volumio volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:41:01 volumio volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:01 volumio volumio[1027]: info: Saving Spotify Acccount Apr 25 18:41:01 volumio volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:41:01 volumio volumio[1027]: info: Got it Apr 25 18:41:01 volumio volumio[1027]: info: Got it Apr 25 18:41:01 volumio volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:01 volumio volumio[1027]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 25 18:41:01 volumio volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:01 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:02 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 25 18:41:02 volumio volumio[1027]: info: Loading i18n strings for locale de Apr 25 18:41:02 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 25 18:41:02 volumio volumio[1027]: info: Setting timezone to Europe/Berlin Apr 25 18:41:02 volumio sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 25 18:41:02 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:02 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:02 volumio sudo[2319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Apr 25 18:41:02 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:02 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:02 volumio sudo[2323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 25 18:41:02 volumio sudo[2323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:02 volumio sudo[2323]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:02 volumio sudo[2327]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Apr 25 18:41:02 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:02 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:02 volumio volumio[1027]: Updating browse sources language Apr 25 18:41:02 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:03 volumio volumio[1027]: info: Fetching Streaming Services browse cache Apr 25 18:41:03 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 25 18:41:03 volumio volumio[1027]: info: MyVolumio login type: Token Apr 25 18:41:04 volumio volumio[1027]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 25 18:41:04 volumio volumio[1027]: info: MyVolumio token set successfully Apr 25 18:41:04 volumio volumio[1027]: info: MYVOLUMIO: Adding device Apr 25 18:41:04 volumio volumio[1027]: info: MYVOLUMIO: Evaluating Server Apr 25 18:41:04 volumio volumio[1027]: info: MyVolumio Plan changed: superstar Apr 25 18:41:04 volumio volumio[1027]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 25 18:41:04 volumio volumio[1027]: info: Adding audio output: Apr 25 18:41:04 volumio volumio[1027]: info: MYVOLUMIO: Adding device Apr 25 18:41:04 volumio volumio[1027]: info: MYVOLUMIO: Evaluating Server Apr 25 18:41:04 volumio volumio[1027]: info: Remote config written successfully Apr 25 18:41:04 volumio volumio[1027]: info: Starting Tunnel 1 Apr 25 18:41:04 volumio volumio[1027]: info: Starting Tunnel Connection Checker Apr 25 18:41:05 volumio volumio[1027]: info: MYVolumio Device enabled Apr 25 18:41:05 volumio volumio[1027]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 25 18:41:05 volumio volumio[1027]: info: MyVolumio status changed Apr 25 18:41:05 volumio volumio[1027]: info: Streaming services startup Apr 25 18:41:05 volumio volumio[1027]: info: Starting Streaming Daemon Apr 25 18:41:05 volumio volumio[1027]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Apr 25 18:41:05 volumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 25 18:41:05 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:05 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:05 volumio volumio[1027]: error: Cannot start Volumio Streaming Daemon Apr 25 18:41:05 volumio volumio[1027]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 25 18:41:05 volumio volumio[1027]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 25 18:41:05 volumio volumio[1027]: info: Setting Geolocation for MyVolumio to eu4 Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:05 volumio volumio[1027]: info: Setting Geolocation for MyVolumio to eu4 Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:05 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: Successfully Added MyVolumio device Apr 25 18:41:06 volumio volumio[1027]: info: Updating MyVolumio device info Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: Successfully Added MyVolumio device Apr 25 18:41:06 volumio volumio[1027]: info: Updating MyVolumio device info Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 25 18:41:06 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 25 18:41:06 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio volumio[1027]: info: MRS: MultiRoom plugin initialized Apr 25 18:41:06 volumio volumio[1027]: info: MRS: STOPPING SNAPCLIENT Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Snap server stop Apr 25 18:41:06 volumio volumio[1027]: info: MRS: STOPPING volumioStreaming Apr 25 18:41:06 volumio sudo[2400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 25 18:41:06 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio sudo[2402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 25 18:41:06 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 25 18:41:06 volumio sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 25 18:41:06 volumio sudo[2398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 25 18:41:06 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 25 18:41:06 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 25 18:41:06 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 25 18:41:06 volumio volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:06 volumio volumio[1027]: info: Successfully Updated MyVolumio device Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Removed streaming files Apr 25 18:41:06 volumio volumio[1027]: info: MRS: volumioStreaming STOPPED Apr 25 18:41:06 volumio volumio[1027]: info: MRS: SNAPSERVER STOPPED Apr 25 18:41:06 volumio volumio[1027]: info: MRS: SNAPCLIENT STOPPED Apr 25 18:41:06 volumio volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Apr 25 18:41:06 volumio volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:06 volumio volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:06 volumio sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:06 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:06 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:06 volumio volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:06 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:06 volumio sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:06 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] onStart Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Apr 25 18:41:06 volumio volumio[1027]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Apr 25 18:41:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Pushing multiroomSync output for this device Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:06 volumio volumio[1027]: info: Adding audio output: Apr 25 18:41:06 volumio volumio[1027]: info: Adding audio output: Apr 25 18:41:06 volumio volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 25 18:41:06 volumio volumio[1027]: info: Adding METAVOLUMIO REST API Endpoints Apr 25 18:41:06 volumio volumio[1027]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 25 18:41:06 volumio volumio[1027]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 25 18:41:06 volumio volumio[1027]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 25 18:41:06 volumio volumio[1027]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 25 18:41:06 volumio volumio[1027]: info: Preparing CD Folders Apr 25 18:41:06 volumio volumio[1027]: info: Adding CD REST API Endpoints Apr 25 18:41:06 volumio volumio[1027]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 25 18:41:06 volumio volumio[1027]: info: Starting UDEV Watcher for CD Apr 25 18:41:06 volumio volumio[1027]: info: Detecting CD presence with UDEV Apr 25 18:41:06 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 25 18:41:06 volumio volumio[1027]: info: Adding inputs REST Endpoints Apr 25 18:41:06 volumio volumio[1027]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 25 18:41:06 volumio volumio[1027]: info: Scanning Audio Inputs Apr 25 18:41:06 volumio volumio[1027]: arecord: device_list:274: no soundcards found... Apr 25 18:41:06 volumio volumio[1027]: info: Adding Server instance for streaming Apr 25 18:41:06 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 25 18:41:07 volumio volumio[1027]: error: Hi Res Audio Failed Login: Missing Login Data Apr 25 18:41:07 volumio volumio[1027]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 25 18:41:07 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 25 18:41:07 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 25 18:41:07 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 25 18:41:07 volumio volumio[1027]: info: Stopping AccessToken refresher cron Apr 25 18:41:07 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:07 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumio[1027]: info: AccessToken refresher cron started Apr 25 18:41:07 volumio volumio[1027]: info: Stopping AccessToken refresher cron for QOBUZ Apr 25 18:41:07 volumio volumio[1027]: info: AccessToken refresher cron started for QOBUZ Apr 25 18:41:07 volumio volumio[1027]: info: Adding TIDAL REST API Endpoints Apr 25 18:41:07 volumio volumio[1027]: info: Adding QOBUZ REST API Endpoints Apr 25 18:41:07 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:07 volumio systemd[1]: mpd.service: Consumed 1.058s CPU time. Apr 25 18:41:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio volumio[1027]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 25 18:41:07 volumio volumio[1027]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Apr 25 18:41:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:07 volumio volumio[1027]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 25 18:41:07 volumio volumio[1027]: info: MPD Permissions set Apr 25 18:41:07 volumio volumio[1027]: info: Successfully Updated MyVolumio device Apr 25 18:41:07 volumio sudo[2436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 25 18:41:07 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Apr 25 18:41:07 volumio sudo[2434]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:07 volumio systemd[1]: alsa-restore.service - Save/Restore Sound Card State was skipped because of an unmet condition check (ConditionPathExistsGlob=/dev/snd/control*). Apr 25 18:41:07 volumio systemd[1]: Reached target sound.target - Sound Card. Apr 25 18:41:07 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:07 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:07 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumiobt[2449]: INFO [BTSTART] Ensuring Bluetooth directory exists... Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Apr 25 18:41:07 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Apr 25 18:41:07 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumio[1027]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::volumioStop Apr 25 18:41:07 volumio volumio[1027]: info: CoreStateMachine::stop Apr 25 18:41:07 volumio volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:41:07 volumio volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Apr 25 18:41:07 volumio volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:41:07 volumio volumiobt[2455]: INFO [BTSTART] Powering on Bluetooth if needed... Apr 25 18:41:07 volumio volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:07 volumio volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:07 volumio sudo[2459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:07 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumiobt[2461]: No default controller available Apr 25 18:41:07 volumio volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Apr 25 18:41:07 volumio volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:07 volumio systemd[1]: shairport-sync.service: Consumed 1.609s CPU time. Apr 25 18:41:07 volumio sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:07 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:07 volumio volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:07 volumio volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:07 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:07 volumio volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:07 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:07 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumio[1027]: info: MPD Permissions set Apr 25 18:41:07 volumio volumio[1027]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 25 18:41:07 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Apr 25 18:41:07 volumio volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:07 volumio volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:07 volumio sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:07 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio sudo[2502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:07 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:07 volumio volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:07 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:07 volumio volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumio[1027]: info: MPD Permissions set Apr 25 18:41:07 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:07 volumio volumio[1027]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 25 18:41:07 volumio volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio sudo[2512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:07 volumio sudo[2512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:07 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:07 volumio volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:07 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:07 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:07 volumio sudo[2513]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:07 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:07 volumio sudo[2512]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:07 volumio volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:07 volumio mpd[2541]: 2025-04-25T18:41:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 18:41:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 18:41:07 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:07 volumio volumio[1027]: error: MPD error: The expression evaluated to a falsy value: Apr 25 18:41:07 volumio volumio[1027]: assert.ok(self.idling) Apr 25 18:41:07 volumio volumio[1027]: error: The expression evaluated to a falsy value: Apr 25 18:41:07 volumio volumio[1027]: assert.ok(self.idling) Apr 25 18:41:07 volumio volumio[1027]: error: updateQueue error: null Apr 25 18:41:07 volumio volumio[1027]: error: updateQueue error: null Apr 25 18:41:08 volumio volumiobt[2545]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Apr 25 18:41:08 volumio volumiobt[2546]: [75B blob data] Apr 25 18:41:08 volumio volumiobt[2546]: No default controller available Apr 25 18:41:08 volumio volumiobt[2546]: [bluetooth]# pairable on Apr 25 18:41:08 volumio volumiobt[2546]: No default controller available Apr 25 18:41:08 volumio volumiobt[2546]: [bluetooth]# Apr 25 18:41:08 volumio volumiobt[2547]: INFO [BTSTART] Registering Bluetooth agent... Apr 25 18:41:08 volumio volumiobt[2549]: No agent is registered Apr 25 18:41:08 volumio volumiobt[2550]: INFO [BTSTART] Agent registered successfully. Apr 25 18:41:08 volumio volumiobt[2551]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Apr 25 18:41:08 volumio volumiobt[2552]: INFO:a2dp-agent:Connecting to system bus. Apr 25 18:41:08 volumio volumiobt[2552]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found Apr 25 18:41:08 volumio volumiobt[2554]: INFO [BTSTART] Disconnecting all Bluetooth devices... Apr 25 18:41:08 volumio volumiobt[2559]: INFO [BTSTART] Unregistering Bluetooth agent... Apr 25 18:41:08 volumio volumiobt[2560]: No agent is registered Apr 25 18:41:08 volumio volumiobt[2561]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Apr 25 18:41:08 volumio volumiobt[2563]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Apr 25 18:41:08 volumio systemd[1]: volumiobt.service: Deactivated successfully. Apr 25 18:41:08 volumio systemd[1]: Stopped target sound.target - Sound Card. Apr 25 18:41:08 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 18:41:08 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:09 volumio volumio-remote-updater[697]: No test mode Apr 25 18:41:09 volumio volumio-remote-updater[697]: No alpha test mode Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 18:41:09 volumio volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:09 volumio volumio-remote-updater[697]: No test mode Apr 25 18:41:09 volumio volumio-remote-updater[697]: No alpha test mode Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 18:41:09 volumio volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:09 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:09 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 25 18:41:09 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 25 18:41:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 25 18:41:09 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 25 18:41:09 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:09 volumio volumio[1027]: info: Remote SSH Started Apr 25 18:41:09 volumio autossh[2576]: port set to 0, monitoring disabled Apr 25 18:41:09 volumio autossh[2576]: starting ssh (count 1) Apr 25 18:41:09 volumio autossh[2576]: ssh child pid is 2579 Apr 25 18:41:10 volumio volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:10 volumio volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:10 volumio volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:10 volumio volumio[1027]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:10 volumio volumio[1027]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:10 volumio sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:10 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:10 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:10 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:10 volumio volumiossh-tunnel[2579]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 25 18:41:10 volumio volumio[1027]: info: Executing endpoint tc_getconfig Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 18:41:10 volumio vtcs[2592]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 18:41:10 volumio vtcs[2592]: STARTED TidalConnect services. Apr 25 18:41:10 volumio volumio[1027]: info: Executing endpoint tc_connect Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 18:41:10 volumio volumio[1027]: info: Connecting to TidalConnect Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:10 volumio volumio[1027]: 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} Apr 25 18:41:10 volumio volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: info: No code Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:10 volumio volumio[1027]: 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} Apr 25 18:41:10 volumio volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: info: No code Apr 25 18:41:10 volumio volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:10 volumio volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:10 volumio volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:10 volumio volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:10 volumio volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:10 volumio volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:10 volumio volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:11 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 18:41:11 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:11 volumio volumio-remote-updater[697]: No test mode Apr 25 18:41:11 volumio volumio-remote-updater[697]: No alpha test mode Apr 25 18:41:11 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 18:41:11 volumio volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:11 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:11 volumio volumio[1027]: info: MRS: Getting audio outputs on start Apr 25 18:41:11 volumio volumio[1027]: info: MRS: Requesting all other devices output Apr 25 18:41:13 volumio volumio[1027]: info: TidalConnect service started! Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 25 18:41:15 volumio volumio[1027]: info: System name has changed, restarting Shairport Sync Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:15 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:15 volumio volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:15 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:15 volumio volumio[1027]: info: Updating MyVolumio device info Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:15 volumio vtcs[2592]: [2025-04-25 18:41:15.073] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 18:41:15 volumio vtcs[2592]: [2025-04-25 18:41:15.075] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 18:41:15 volumio volumio[1027]: info: Discovery: Restarting Advertising due to device name change Apr 25 18:41:15 volumio sudo[2628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:15 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 25 18:41:15 volumio sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio sudo[2633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:15 volumio sudo[2633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio sudo[2630]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 18:41:15 volumio volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:15 volumio systemd[1]: vtcs.service: Deactivated successfully. Apr 25 18:41:15 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 25 18:41:15 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 25 18:41:15 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:15 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:15 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:15 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio volumio[1027]: info: Permissions for /etc/hosts set Apr 25 18:41:15 volumio sudo[2633]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio volumio[1027]: info: Hostname now is volumio-home Apr 25 18:41:15 volumio systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Apr 25 18:41:15 volumio sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-home Apr 25 18:41:15 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio-home sudo[2648]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio-home sudo[2651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Apr 25 18:41:15 volumio-home sudo[2651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:15 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:15 volumio-home sudo[2651]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio-home volumio[1027]: info: New hostname set Apr 25 18:41:15 volumio-home volumio[1027]: info: Permissions for /etc/avahi/services/volumio.service Apr 25 18:41:15 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:15 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:15 volumio-home systemd[1]: shairport-sync.service: Consumed 1.532s CPU time. Apr 25 18:41:15 volumio-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:15 volumio-home avahi-daemon[679]: Files changed, reloading. Apr 25 18:41:15 volumio-home avahi-daemon[679]: Loading service file /services/volumio.service. Apr 25 18:41:15 volumio-home volumio[1027]: info: Avahi name changed to volumio-home Apr 25 18:41:15 volumio-home sudo[2639]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:15 volumio-home systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Apr 25 18:41:15 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:15 volumio-home systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Apr 25 18:41:15 volumio-home volumio[1027]: info: Successfully Updated MyVolumio device Apr 25 18:41:16 volumio-home volumio[1027]: info: Discovery: A device disappeared from network Apr 25 18:41:16 volumio-home volumio[1027]: info: Discovery: Device volumio disappeared from network Apr 25 18:41:16 volumio-home volumio[1027]: info: Discovery: A device disappeared from network Apr 25 18:41:16 volumio-home avahi-daemon[679]: Service "Volumio Home" (/services/volumio.service) successfully established. Apr 25 18:41:18 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:18 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:18 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:18 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:18 volumio-home sudo[2682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:18 volumio-home sudo[2682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:18 volumio-home sudo[2682]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Executing endpoint tc_getconfig Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 18:41:18 volumio-home vtcs[2685]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 18:41:18 volumio-home vtcs[2685]: STARTED TidalConnect services. Apr 25 18:41:18 volumio-home volumio[1027]: info: Executing endpoint tc_connect Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 18:41:18 volumio-home volumio[1027]: info: Connecting to TidalConnect Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:18 volumio-home volumio[1027]: 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} Apr 25 18:41:18 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: info: No code Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:18 volumio-home volumio[1027]: 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} Apr 25 18:41:18 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: info: No code Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 25 18:41:18 volumio-home volumio[1027]: info: Preparing to save Alsa Options, stopping services first Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:18 volumio-home volumio[1027]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"nodev","label":"Kein Audiogerät verfügbar"},"disallowPush":true} Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: warn: Unable to locate the device Kein Audiogerät verfügbar a reboot may be required. Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:18 volumio-home volumio[1027]: Invalid card number 'nodev'. Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 25 18:41:18 volumio-home volumio[1027]: info: Updating Volume Controller Parameters: Device: nodev Name: Kein Audiogerät verfügbar Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 25 18:41:18 volumio-home volumio[1027]: info: Disabling external Volume Control Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:41:18 volumio-home volumio[1027]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 18:41:18 volumio-home volumio[1027]: info: Reading ALSA contributions from plugins. Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:18 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:18 volumio-home volumio[1027]: Invalid card number 'nodev'. Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 18:41:18 volumio-home volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Saving Spotify Acccount Apr 25 18:41:18 volumio-home volumio[1027]: info: Got it Apr 25 18:41:18 volumio-home volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:18 volumio-home volumio[1027]: info: Got it Apr 25 18:41:18 volumio-home volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:18 volumio-home volumio[1027]: info: Asound.conf file written Apr 25 18:41:18 volumio-home sudo[2726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 18:41:18 volumio-home sudo[2726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2726]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home sudo[2736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:18 volumio-home sudo[2736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:18 volumio-home sudo[2732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2732]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home vtcs[2685]: [2025-04-25 18:41:18.430] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 18:41:18 volumio-home vtcs[2685]: [2025-04-25 18:41:18.430] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 18:41:18 volumio-home systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:18 volumio-home sudo[2744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:18 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:18 volumio-home sudo[2746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:18 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:18 volumio-home sudo[2746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:18 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2746]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home sudo[2760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:41:18 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:41:18 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:41:18 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:41:18 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:41:18 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:41:18 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:41:18 volumio-home sudo[2762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:18 volumio-home systemd[1]: vtcs.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:18 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:18 volumio-home sudo[2744]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:18 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:18 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:18 volumio-home sudo[2788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:18 volumio-home sudo[2788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:18 volumio-home volumio[1027]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 18:41:18 volumio-home volumio[1027]: info: Reading ALSA contributions from plugins. Apr 25 18:41:18 volumio-home volumio[1027]: info: Asound.conf file written Apr 25 18:41:18 volumio-home sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:18 volumio-home sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:18 volumio-home sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 18:41:18 volumio-home sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2795]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:18 volumio-home sudo[2760]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2762]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home sudo[2801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home sudo[2801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:18 volumio-home sudo[2805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2765]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2801]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2786]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:18 volumio-home volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:18 volumio-home sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:18 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:18 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:18 volumio-home systemd[1]: shairport-sync.service: Consumed 1.528s CPU time. Apr 25 18:41:18 volumio-home sudo[2786]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 18:41:18 volumio-home volumio[1027]: info: Reading ALSA contributions from plugins. Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:41:18 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:41:18 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:41:18 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home sudo[2820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:18 volumio-home sudo[2820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:18 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:18 volumio-home sudo[2788]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2790]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:18 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:18 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:18 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:18 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:18 volumio-home volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:18 volumio-home sudo[2851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:18 volumio-home sudo[2851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home sudo[2848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:18 volumio-home sudo[2848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home sudo[2851]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:18 volumio-home sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:18 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:18 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home sudo[2816]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2820]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2849]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:18 volumio-home sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:18 volumio-home sudo[2849]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:41:18 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:41:18 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:41:18 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:41:18 volumio-home sudo[2865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:18 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:18 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home sudo[2869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:18 volumio-home sudo[2869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:18 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:18 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:18 volumio-home sudo[2882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:18 volumio-home sudo[2882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:18 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:18 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:18 volumio-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:18 volumio-home sudo[2882]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:18 volumio-home sudo[2848]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:18 volumio-home sudo[2865]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home volumio[1027]: (node:1027) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 25 18:41:18 volumio-home volumio[1027]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 25 18:41:18 volumio-home volumio[1027]: (node:1027) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 25 18:41:18 volumio-home volumio[1027]: (node:1027) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 25 18:41:18 volumio-home volumio[1027]: (node:1027) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 25 18:41:18 volumio-home volumio[1027]: (node:1027) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 25 18:41:18 volumio-home sudo[2869]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:18 volumio-home sudo[2873]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:18 volumio-home sudo[2873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:18 volumio-home sudo[2873]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:19 volumio-home mpd[2902]: 2025-04-25T18:41:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 18:41:19 volumio-home systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 18:41:19 volumio-home sudo[2748]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:19 volumio-home sudo[2736]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:19 volumio-home sudo[2853]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:19 volumio-home sudo[2805]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:19 volumio-home volumio[1027]: error: MPD error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: MPD error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: MPD error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: The expression evaluated to a falsy value: Apr 25 18:41:19 volumio-home volumio[1027]: assert.ok(self.idling) Apr 25 18:41:19 volumio-home volumio[1027]: error: updateQueue error: null Apr 25 18:41:20 volumio-home volumio[1027]: info: Discovery: Started advertising with name: Volumio Home Apr 25 18:41:20 volumio-home volumio[1027]: info: Discovery: adding 198d46a8-db51-46d2-8e64-5811a6915bdf Apr 25 18:41:20 volumio-home volumio[1027]: info: Discovery: Found device Volumio Home Apr 25 18:41:20 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:20 volumio-home volumio[1027]: info: Discovery: this is already registered, 198d46a8-db51-46d2-8e64-5811a6915bdf Apr 25 18:41:20 volumio-home volumio[1027]: info: Discovery: Found device Volumio Home Apr 25 18:41:20 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:21 volumio-home sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home sudo[2928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:21 volumio-home sudo[2928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:21 volumio-home systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:21 volumio-home sudo[2917]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:21 volumio-home sudo[2928]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:21 volumio-home volumio[1027]: info: Executing endpoint tc_getconfig Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 18:41:21 volumio-home vtcs[2931]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home vtcs[2931]: STARTED TidalConnect services. Apr 25 18:41:21 volumio-home volumio[1027]: info: Executing endpoint tc_connect Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 18:41:21 volumio-home volumio[1027]: info: Connecting to TidalConnect Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:21 volumio-home volumio[1027]: 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} Apr 25 18:41:21 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: info: No code Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:21 volumio-home volumio[1027]: 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} Apr 25 18:41:21 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: info: No code Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:21 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:21 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home sudo[2957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:21 volumio-home sudo[2957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:21 volumio-home sudo[2957]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:21 volumio-home sudo[2969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:21 volumio-home sudo[2969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:21 volumio-home sudo[2969]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreStateMachine::getcurrentVolume Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:41:22 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:22 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:22 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:22 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreStateMachine::updateTrackBlock Apr 25 18:41:22 volumio-home volumio[1027]: info: CorePlayQueue::getTrackBlock Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:41:22 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:22 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:22 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:22 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:22 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:22 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:22 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:24 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:24 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:24 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:24 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:25 volumio-home sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Apr 25 18:41:25 volumio-home sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Apr 25 18:41:25 volumio-home sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home sudo[3017]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home volumio[1027]: info: smb.conf Permissions set Apr 25 18:41:25 volumio-home sudo[3019]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home volumio[1027]: info: Internal perms successfully set Apr 25 18:41:25 volumio-home sudo[3023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Apr 25 18:41:25 volumio-home sudo[3023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home nmbd[981]: [2025/04/25 18:41:25.110349, 0] ../../source3/nmbd/nmbd.c:59(terminate) Apr 25 18:41:25 volumio-home systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Apr 25 18:41:25 volumio-home sudo[3026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 18:41:25 volumio-home sudo[3026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home systemd[1]: nmbd.service: Deactivated successfully. Apr 25 18:41:25 volumio-home nmbd[981]: Got SIGTERM: going down... Apr 25 18:41:25 volumio-home systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Apr 25 18:41:25 volumio-home sudo[3026]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 18:41:25 volumio-home sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home sudo[3028]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 25 18:41:25 volumio-home sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home systemd[1]: Starting nmbd.service - Samba NMB Daemon... Apr 25 18:41:25 volumio-home sudo[3035]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home volumio[1027]: info: Upmpdcli Daemon Started Apr 25 18:41:25 volumio-home nmbd[3046]: [2025/04/25 18:41:25.285408, 0] ../../source3/nmbd/nmbd.c:901(main) Apr 25 18:41:25 volumio-home nmbd[3046]: nmbd version 4.17.12-Debian started. Apr 25 18:41:25 volumio-home nmbd[3046]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 25 18:41:25 volumio-home nmbd[3046]: [2025/04/25 18:41:25.286960, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 25 18:41:25 volumio-home nmbd[3046]: started asyncdns process 3047 Apr 25 18:41:25 volumio-home systemd[1]: Started nmbd.service - Samba NMB Daemon. Apr 25 18:41:25 volumio-home sudo[3023]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home sudo[3049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Apr 25 18:41:25 volumio-home sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:25 volumio-home systemd[1]: Stopping smbd.service - Samba SMB Daemon... Apr 25 18:41:25 volumio-home systemd[1]: smbd.service: Deactivated successfully. Apr 25 18:41:25 volumio-home systemd[1]: Stopped smbd.service - Samba SMB Daemon. Apr 25 18:41:25 volumio-home systemd[1]: Starting smbd.service - Samba SMB Daemon... Apr 25 18:41:25 volumio-home smbd[3054]: [2025/04/25 18:41:25.393593, 0] ../../source3/smbd/server.c:1741(main) Apr 25 18:41:25 volumio-home smbd[3054]: smbd version 4.17.12-Debian started. Apr 25 18:41:25 volumio-home smbd[3054]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 25 18:41:25 volumio-home systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 25 18:41:25 volumio-home sudo[3049]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:25 volumio-home volumio[1027]: info: SAMBA Restarted Apr 25 18:41:32 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 18:41:32 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:32 volumio-home systemd[1]: systemd-timedated.service: Deactivated successfully. Apr 25 18:41:32 volumio-home volumio-remote-updater[697]: No test mode Apr 25 18:41:32 volumio-home volumio-remote-updater[697]: Alpha test mode enabled Apr 25 18:41:32 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 18:41:32 volumio-home volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:32 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:45 volumio-home volumio[1027]: info: Received OAUTH Data Apr 25 18:41:45 volumio-home volumio[1027]: info: Refreshing QOBUZ token Apr 25 18:41:45 volumio-home volumio[1027]: info: Access Token successfully retrieved Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 25 18:41:45 volumio-home volumio[1027]: info: [1745599305131] CoreMusicLibrary::Adding element QOBUZ Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:45 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Saving Spotify Acccount Apr 25 18:41:45 volumio-home volumio[1027]: info: Got it Apr 25 18:41:45 volumio-home volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:41:45 volumio-home volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Got it Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home sudo[3191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 18:41:45 volumio-home sudo[3191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:45 volumio-home sudo[3193]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 18:41:45 volumio-home sudo[3191]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:45 volumio-home sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:45 volumio-home sudo[3193]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:45 volumio-home volumio[1027]: verbose: New Socket.io Connection to 192.168.178.23 from 192.168.178.21 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 25 18:41:45 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 25 18:41:45 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:45 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:45 volumio-home volumio-remote-updater[697]: No test mode Apr 25 18:41:45 volumio-home volumio-remote-updater[697]: Alpha test mode enabled Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Saving Spotify Acccount Apr 25 18:41:45 volumio-home volumio[1027]: info: Got it Apr 25 18:41:45 volumio-home volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:41:45 volumio-home volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:41:45 volumio-home volumio[1027]: info: Got it Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:45 volumio-home volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:41:45 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:41:46 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 25 18:41:46 volumio-home volumio[1027]: info: Loading i18n strings for locale de Apr 25 18:41:46 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 25 18:41:46 volumio-home volumio[1027]: info: Setting timezone to Europe/Berlin Apr 25 18:41:46 volumio-home sudo[3205]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 25 18:41:46 volumio-home sudo[3205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:46 volumio-home sudo[3205]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:46 volumio-home sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Apr 25 18:41:46 volumio-home sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:46 volumio-home sudo[3209]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:46 volumio-home sudo[3213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 25 18:41:46 volumio-home sudo[3213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:46 volumio-home sudo[3213]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:46 volumio-home sudo[3217]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Apr 25 18:41:46 volumio-home sudo[3217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:46 volumio-home dbus-daemon[682]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.56' (uid=0 pid=3218 comm="/usr/bin/timedatectl set-timezone Europe/Berlin") Apr 25 18:41:46 volumio-home systemd[1]: Starting systemd-timedated.service - Time & Date Service... Apr 25 18:41:46 volumio-home dbus-daemon[682]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 25 18:41:46 volumio-home systemd[1]: Started systemd-timedated.service - Time & Date Service. Apr 25 18:41:46 volumio-home sudo[3217]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:46 volumio-home volumio[1027]: Updating browse sources language Apr 25 18:41:46 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:41:46 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:46 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:41:47 volumio-home volumio[1027]: info: Fetching Streaming Services browse cache Apr 25 18:41:47 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:48 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:50 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 25 18:41:50 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 25 18:41:50 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:50 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 18:41:50 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:50 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 25 18:41:51 volumio-home volumio[1027]: info: Preparing to save Alsa Options, stopping services first Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:51 volumio-home volumio[1027]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"nodev","label":"Kein Audiogerät verfügbar"},"disallowPush":true} Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: warn: Unable to locate the device Kein Audiogerät verfügbar a reboot may be required. Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:51 volumio-home volumio[1027]: Invalid card number 'nodev'. Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 25 18:41:51 volumio-home volumio[1027]: info: Updating Volume Controller Parameters: Device: nodev Name: Kein Audiogerät verfügbar Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 25 18:41:51 volumio-home volumio[1027]: info: Disabling external Volume Control Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:41:51 volumio-home volumio[1027]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 18:41:51 volumio-home volumio[1027]: info: Reading ALSA contributions from plugins. Apr 25 18:41:51 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:51 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:51 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:51 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:51 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 18:41:51 volumio-home volumio[1027]: Invalid card number 'nodev'. Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 18:41:51 volumio-home volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:41:51 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:41:51 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Saving Spotify Acccount Apr 25 18:41:51 volumio-home volumio[1027]: info: Got it Apr 25 18:41:51 volumio-home volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:41:51 volumio-home volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:41:51 volumio-home volumio[1027]: info: Got it Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:41:51 volumio-home volumio[1027]: info: Asound.conf file unchanged, so no further update is needed Apr 25 18:41:51 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:41:51 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:51 volumio-home sudo[3244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:41:51 volumio-home sudo[3244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:51 volumio-home sudo[3246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:41:51 volumio-home sudo[3246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:41:51 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:51 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home sudo[3244]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home vtcs[2931]: [2025-04-25 18:41:51.388] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 18:41:51 volumio-home vtcs[2931]: [2025-04-25 18:41:51.388] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 18:41:51 volumio-home systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 18:41:51 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:41:51 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:41:51 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:41:51 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:41:51 volumio-home sudo[3256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:51 volumio-home sudo[3256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:51 volumio-home sudo[3258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:41:51 volumio-home sudo[3258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:51 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:41:51 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:41:51 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:41:51 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:41:51 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:41:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:51 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:41:51 volumio-home sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:41:51 volumio-home sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:51 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:41:51 volumio-home systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 18:41:51 volumio-home systemd[1]: vtcs.service: Killing process 2939 (vtcs) with signal SIGKILL. Apr 25 18:41:51 volumio-home systemd[1]: vtcs.service: Deactivated successfully. Apr 25 18:41:51 volumio-home systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:51 volumio-home sudo[3256]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home sudo[3258]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:41:51 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:41:51 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:51 volumio-home systemd[1]: shairport-sync.service: Consumed 1.574s CPU time. Apr 25 18:41:51 volumio-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:41:51 volumio-home sudo[3271]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:41:51 volumio-home sudo[3268]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:41:51 volumio-home sudo[3268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:41:51 volumio-home sudo[3268]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home mpd[3277]: 2025-04-25T18:41:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 18:41:51 volumio-home systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 18:41:51 volumio-home sudo[3246]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:51 volumio-home volumio[1027]: error: updateQueue error: null Apr 25 18:41:53 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 25 18:41:53 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 25 18:41:53 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:53 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 25 18:41:53 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 25 18:41:53 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 25 18:41:53 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:41:54 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:54 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:41:54 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:54 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:41:54 volumio-home sudo[3311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:41:54 volumio-home sudo[3311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:54 volumio-home systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 18:41:54 volumio-home sudo[3311]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:54 volumio-home volumio[1027]: info: Executing endpoint tc_getconfig Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 18:41:54 volumio-home vtcs[3328]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 18:41:54 volumio-home vtcs[3328]: STARTED TidalConnect services. Apr 25 18:41:54 volumio-home volumio[1027]: info: Executing endpoint tc_connect Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 18:41:54 volumio-home volumio[1027]: info: Connecting to TidalConnect Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:54 volumio-home volumio[1027]: 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} Apr 25 18:41:54 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: info: No code Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:41:54 volumio-home volumio[1027]: 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} Apr 25 18:41:54 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: info: No code Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:54 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::Reload Ui Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 25 18:41:54 volumio-home volumio[1027]: info: Wizard terminated Successfully Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::volumiosetStartupVolume Apr 25 18:41:54 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreStateMachine::getcurrentVolume Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:41:55 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:55 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:55 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:55 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreStateMachine::updateTrackBlock Apr 25 18:41:55 volumio-home volumio[1027]: info: CorePlayQueue::getTrackBlock Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:41:55 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:55 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:41:55 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:41:55 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:41:55 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:41:55 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:41:55 volumio-home volumio[1027]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ZPn0Qm7GXqfb9KMg9S0wlRghkMn2.c89f5727c9b92718a72743a0fab0a2e6.state.status' Apr 25 18:41:55 volumio-home volumio[1027]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 25 18:41:56 volumio-home sudo[3358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 18:41:56 volumio-home sudo[3360]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 18:41:56 volumio-home sudo[3360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:56 volumio-home sudo[3358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:56 volumio-home sudo[3358]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:56 volumio-home sudo[3360]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:56 volumio-home volumio[1027]: verbose: New Socket.io Connection to 192.168.178.23 from 192.168.178.21 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 25 18:41:56 volumio-home volumio[1027]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 18:41:56 volumio-home volumio[1027]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 25 18:41:56 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:56 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:56 volumio-home volumio[1027]: info: Listing playlists Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 18:41:56 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 25 18:41:56 volumio-home sudo[3366]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 18:41:56 volumio-home sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:56 volumio-home sudo[3368]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 18:41:56 volumio-home sudo[3366]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:56 volumio-home sudo[3368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:41:56 volumio-home sudo[3368]: pam_unix(sudo:session): session closed for user root Apr 25 18:41:56 volumio-home volumio[1027]: verbose: New Socket.io Connection to 192.168.178.23 from 192.168.178.21 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 25 18:41:57 volumio-home volumio[1027]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 25 18:41:57 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:57 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:57 volumio-home volumio[1027]: info: Listing playlists Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:57 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:41:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:58 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:58 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:58 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:58 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:59 volumio-home volumio[1027]: info: Executing endpoint metavolumio Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:59 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:59 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:41:59 volumio-home volumio[1027]: info: Executing endpoint metavolumio Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 18:41:59 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:41:59 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:41:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:42:01 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 25 18:42:02 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: Retrieving Cloud Streaming UI Apr 25 18:42:06 volumio-home volumio[1027]: info: Getting Tidal Cloud Configuration Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: Getting Qobuz Cloud Configuration Apr 25 18:42:06 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: Getting Spotify Cloud Configuration Apr 25 18:42:06 volumio-home volumio[1027]: info: Asking plugin for UI Config Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: Saving Spotify Acccount Apr 25 18:42:06 volumio-home volumio[1027]: info: Got it Apr 25 18:42:06 volumio-home volumio[1027]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 18:42:06 volumio-home volumio[1027]: info: Got Tidal Cloud Configuration Apr 25 18:42:06 volumio-home volumio[1027]: info: Got it Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 25 18:42:06 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 25 18:42:08 volumio-home volumio[1027]: info: CoreCommandRouter::Close All Modals sent Apr 25 18:42:08 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Apr 25 18:42:10 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:12 volumio-home volumio[1027]: info: Disabling MyMusic plugin tidalconnect Apr 25 18:42:12 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Apr 25 18:42:12 volumio-home vtcs[3328]: [2025-04-25 18:42:12.762] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 18:42:12 volumio-home vtcs[3328]: [2025-04-25 18:42:12.762] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 18:42:12 volumio-home volumio[1027]: info: Disabling plugin tidalconnect Apr 25 18:42:12 volumio-home sudo[3403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:42:12 volumio-home sudo[3403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:12 volumio-home systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 18:42:12 volumio-home systemd[1]: vtcs.service: Killing process 3332 (vtcs) with signal SIGKILL. Apr 25 18:42:12 volumio-home systemd[1]: vtcs.service: Deactivated successfully. Apr 25 18:42:12 volumio-home systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 18:42:12 volumio-home sudo[3403]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:13 volumio-home volumio[1027]: info: Disabling MyMusic plugin smart_inputs Apr 25 18:42:13 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service smart_inputs Apr 25 18:42:13 volumio-home volumio[1027]: info: Removeing all smart Inputs Apr 25 18:42:13 volumio-home volumio[1027]: info: Disabling plugin smart_inputs Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 18:42:14 volumio-home volumio[1027]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 25 18:42:14 volumio-home volumio[1027]: info: Received Get System Version Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 18:42:14 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:42:14 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:42:14 volumio-home volumio[1027]: info: Disabling MyMusic plugin cd_controller Apr 25 18:42:14 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:42:14 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:42:14 volumio-home volumio[1027]: info: Stopping UDEV Watcher for CD Apr 25 18:42:14 volumio-home volumio[1027]: info: Disabling plugin cd_controller Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 18:42:14 volumio-home volumio[1027]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 25 18:42:14 volumio-home volumio[1027]: info: Received Get System Version Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 18:42:14 volumio-home volumio[1027]: info: Received Get System Info Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:42:14 volumio-home volumio[1027]: info: Discovery: Getting this device information Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Apr 25 18:42:14 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:42:15 volumio-home volumio[1027]: info: Disabling MyMusic plugin metavolumio Apr 25 18:42:15 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin miscellanea metavolumio Apr 25 18:42:15 volumio-home volumio[1027]: info: Removing METAVOLUMIO REST API Endpoints Apr 25 18:42:15 volumio-home volumio[1027]: info: Removing metavolumio REST Endpoint Apr 25 18:42:15 volumio-home volumio[1027]: info: Removing getSimilarArtists REST Endpoint Apr 25 18:42:15 volumio-home volumio[1027]: info: Removing getSimilarAlbums REST Endpoint Apr 25 18:42:15 volumio-home volumio[1027]: info: Removing getSimilarTracks REST Endpoint Apr 25 18:42:15 volumio-home volumio[1027]: info: Disabling plugin metavolumio Apr 25 18:42:15 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:42:16 volumio-home volumio[1027]: info: Disabling MyMusic plugin multiroom Apr 25 18:42:16 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Apr 25 18:42:16 volumio-home volumio[1027]: info: Disabling plugin multiroom Apr 25 18:42:16 volumio-home volumio[1027]: info: Preparing to generate the ALSA configuration file Apr 25 18:42:16 volumio-home volumio[1027]: info: Asound.conf file written Apr 25 18:42:16 volumio-home sudo[3422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 18:42:16 volumio-home sudo[3422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home sudo[3422]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home volumio[1027]: info: Output device has changed, restarting MPD Apr 25 18:42:16 volumio-home sudo[3428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 18:42:16 volumio-home sudo[3428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home volumio[1027]: info: Output device has changed, restarting Shairport Sync Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:42:16 volumio-home sudo[3428]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home sudo[3430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 18:42:16 volumio-home sudo[3430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:42:16 volumio-home volumio[1027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 18:42:16 volumio-home volumio[1027]: aplay: device_list:274: no soundcards found... Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 18:42:16 volumio-home sudo[3440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:42:16 volumio-home sudo[3440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home systemd[1]: mpd.service: Deactivated successfully. Apr 25 18:42:16 volumio-home systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 18:42:16 volumio-home systemd[1]: mpd.socket: Deactivated successfully. Apr 25 18:42:16 volumio-home systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 18:42:16 volumio-home systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 18:42:16 volumio-home systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 18:42:16 volumio-home volumio[1027]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C nodev Apr 25 18:42:16 volumio-home volumio[1027]: The -C option requires a numerical argument! Aborting. Apr 25 18:42:16 volumio-home volumio[1027]: {"cmd":"/usr/local/bin/alsacap -C nodev","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C nodev\nThe -C option requires a numerical argument! Aborting.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 25 18:42:16 volumio-home volumio[1027]: info: MPD Permissions set Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home sudo[3443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 18:42:16 volumio-home sudo[3443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 18:42:16 volumio-home volumio[1027]: info: Starting Shairport Sync Apr 25 18:42:16 volumio-home systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 18:42:16 volumio-home sudo[3455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 18:42:16 volumio-home sudo[3455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home sudo[3443]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home sudo[3440]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:42:16 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:42:16 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:42:16 volumio-home systemd[1]: shairport-sync.service: Consumed 1.569s CPU time. Apr 25 18:42:16 volumio-home systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:42:16 volumio-home sudo[3455]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home volumio[1027]: info: Shairport-Sync Started Apr 25 18:42:16 volumio-home sudo[3444]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 18:42:16 volumio-home sudo[3444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 18:42:16 volumio-home sudo[3444]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home mpd[3462]: 2025-04-25T18:42:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 18:42:16 volumio-home systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 18:42:16 volumio-home sudo[3430]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home volumio[1027]: error: updateQueue error: null Apr 25 18:42:16 volumio-home volumio[1027]: info: Disabling MyMusic plugin bluetooth Apr 25 18:42:16 volumio-home volumio[1027]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] onStop Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Apr 25 18:42:16 volumio-home sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Apr 25 18:42:16 volumio-home sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 18:42:16 volumio-home sudo[3480]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Apr 25 18:42:16 volumio-home volumio[1027]: verbose: UNSET VOLATILE: Service: undefined Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::resetVolumioState Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::getcurrentVolume Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::resetVolumioState Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::getcurrentVolume Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioStop Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::stop Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioStop Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::stop Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:42:16 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:42:16 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:42:16 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::updateTrackBlock Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrackBlock Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:42:16 volumio-home systemd[1]: systemd-timedated.service: Deactivated successfully. Apr 25 18:42:16 volumio-home volumio[1027]: ------------------------------------ BT MESSAGE: Failed to power off adapter: Apr 25 18:42:16 volumio-home volumio[1027]: info: Disabling plugin bluetooth Apr 25 18:42:16 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:42:16 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::updateTrackBlock Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrackBlock Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 18:42:16 volumio-home volumio[1027]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number 'nodev'. Apr 25 18:42:16 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:16 volumio-home volumio[1027]: info: VolumeController:: Volume=undefined Mute =false Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:16 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:16 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:16 volumio-home volumio-remote-updater[697]: No test mode Apr 25 18:42:16 volumio-home volumio-remote-updater[697]: Alpha test mode enabled Apr 25 18:42:16 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 18:42:17 volumio-home volumio[1027]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 18:42:17 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 18:42:17 volumio-home volumio[1027]: info: Disabling MyMusic plugin upnp_browser Apr 25 18:42:17 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedienserver Apr 25 18:42:17 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:42:17 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:42:17 volumio-home volumio[1027]: info: Disabling plugin upnp_browser Apr 25 18:42:17 volumio-home volumio[1027]: info: Done. Apr 25 18:42:18 volumio-home volumio[1027]: info: Disabling MyMusic plugin airplay_emulation Apr 25 18:42:18 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Apr 25 18:42:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 18:42:18 volumio-home volumio[1027]: Cannot find translation for source QOBUZ Apr 25 18:42:18 volumio-home volumio[1027]: info: Disabling plugin airplay_emulation Apr 25 18:42:18 volumio-home volumio[1027]: info: Done. Apr 25 18:42:18 volumio-home sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 25 18:42:18 volumio-home sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:18 volumio-home systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 18:42:18 volumio-home systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 18:42:18 volumio-home systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 18:42:18 volumio-home systemd[1]: shairport-sync.service: Consumed 1.525s CPU time. Apr 25 18:42:18 volumio-home sudo[3496]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:18 volumio-home volumio[1027]: info: Shairport-Sync Stopped Apr 25 18:42:18 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:19 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:42:19 volumio-home volumio[1027]: info: TidalConnect service stoped! Apr 25 18:42:19 volumio-home volumio[1027]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:42:19 volumio-home volumio[1027]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 18:42:19 volumio-home sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 18:42:19 volumio-home sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:19 volumio-home systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 18:42:19 volumio-home sudo[3507]: pam_unix(sudo:session): session closed for user root Apr 25 18:42:19 volumio-home volumio[1027]: info: Executing endpoint tc_getconfig Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 18:42:19 volumio-home vtcs[3510]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 18:42:19 volumio-home vtcs[3510]: STARTED TidalConnect services. Apr 25 18:42:19 volumio-home volumio[1027]: info: Executing endpoint tc_connect Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 18:42:19 volumio-home volumio[1027]: info: Connecting to TidalConnect Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: 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} Apr 25 18:42:19 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:19 volumio-home volumio[1027]: info: No code Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::servicePushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: 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} Apr 25 18:42:19 volumio-home volumio[1027]: verbose: CURRENT POSITION 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService stop Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:19 volumio-home volumio[1027]: info: No code Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreStateMachine::pushState Apr 25 18:42:19 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Apr 25 18:42:19 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:42:19 volumio-home volumio[1027]: info: MRS: Pushing multiroomSync output Apr 25 18:42:21 volumio-home volumio[1027]: info: Disabling MyMusic plugin upnp Apr 25 18:42:21 volumio-home sudo[3527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 25 18:42:21 volumio-home sudo[3527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 18:42:21 volumio-home systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Apr 25 18:42:22 volumio-home volumio[1027]: info: TidalConnect service started! Apr 25 18:42:22 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:26 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:30 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 18:42:34 volumio-home volumio[1027]: info: Enabling MyMusic plugin upnp Apr 25 18:42:34 volumio-home volumio[1027]: info: Enabling plugin upnp Apr 25 18:42:34 volumio-home volumio[1027]: info: Loading plugin "upnp"... Apr 25 18:42:34 volumio-home volumio[1027]: info: [1745599354134] Starting Upmpd Daemon Apr 25 18:42:34 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 18:42:34 volumio-home volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 18:42:34 volumio-home volumio[1027]: Error: listen EADDRINUSE: address already in use :::6599 Apr 25 18:42:34 volumio-home volumio[1027]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Apr 25 18:42:34 volumio-home volumio[1027]: at listenInCluster (node:net:1920:12) Apr 25 18:42:34 volumio-home volumio[1027]: at Server.listen (node:net:2008:7) Apr 25 18:42:34 volumio-home volumio[1027]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 25 18:42:34 volumio-home volumio[1027]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 25 18:42:34 volumio-home volumio[1027]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 25 18:42:34 volumio-home volumio[1027]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 25 18:42:34 volumio-home volumio[1027]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Apr 25 18:42:34 volumio-home volumio[1027]: code: 'EADDRINUSE', Apr 25 18:42:34 volumio-home volumio[1027]: errno: -98, Apr 25 18:42:34 volumio-home volumio[1027]: syscall: 'listen', Apr 25 18:42:34 volumio-home volumio[1027]: address: '::', Apr 25 18:42:34 volumio-home volumio[1027]: port: 6599 Apr 25 18:42:34 volumio-home volumio[1027]: } Apr 25 18:42:34 volumio-home volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 18:42:34 volumio-home sudo[3557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 18:41' Apr 25 18:42:34 volumio-home sudo[3557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5431d94b4ec10a9432d7232ad6f9406bf7be8de2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Apr 24 13:18:51 UTC 2025" VOLUMIO_VERSION="0.060" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="b52e798d3aee410c6676cfef810c42a3"