Apr 25 21:09:00 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 21:09:00 volumio volumio[2254]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Apr 25 21:09:00 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:00 volumio volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 21:09:01 volumio volumio[2254]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 25 21:09:01 volumio volumio[2254]: info: Received Get System Version Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 21:09:01 volumio volumio[2254]: info: Received Get System Info Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 21:09:01 volumio volumio[2254]: info: Discovery: Getting this device information Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:01 volumio volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:09:01 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 21:09:05 volumio volumio[2254]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 25 21:09:05 volumio volumio[2254]: info: Completed starting MyVolumio Plugin Apr 25 21:09:05 volumio volumio[2254]: [Metrics] CommandRouter: 31s 364.20ms Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::volumiosetStartupVolume Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::Close All Modals sent Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::Close All Modals sent Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Retrieving Cloud Streaming UI Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Retrieving Cloud Streaming UI Apr 25 21:09:05 volumio volumio[2254]: info: Getting Tidal Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Getting Qobuz Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Getting Spotify Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Saving Spotify Acccount Apr 25 21:09:05 volumio volumio[2254]: info: Got Tidal Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Got it Apr 25 21:09:05 volumio volumio[2254]: info: Got it Apr 25 21:09:05 volumio volumio[2254]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 21:09:05 volumio volumio[2254]: info: Getting Tidal Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Getting Qobuz Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Getting Spotify Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: info: Saving Spotify Acccount Apr 25 21:09:05 volumio volumio[2254]: info: Got Tidal Cloud Configuration Apr 25 21:09:05 volumio volumio[2254]: info: Got it Apr 25 21:09:05 volumio volumio[2254]: info: Got it Apr 25 21:09:05 volumio volumio[2254]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 25 21:09:05 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:05 volumio volumio[2254]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 25 21:09:06 volumio kernel: snd_hda_intel 0000:00:03.0: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 21:09:06 volumio volumio-remote-updater[852]: No test mode Apr 25 21:09:06 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 21:09:06 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 25 21:09:06 volumio volumio[2254]: info: Version has changed, forcing UI Reload Apr 25 21:09:06 volumio volumio[2254]: info: CoreCommandRouter::Reload Ui Apr 25 21:09:07 volumio sudo[2784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 21:09:07 volumio volumio[2254]: 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: 12 Apr 25 21:09:07 volumio sudo[2782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 21:09:07 volumio sudo[2782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:07 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:07 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:07 volumio sudo[2782]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 25 21:09:07 volumio volumio[2254]: info: Received Get System Info Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 21:09:07 volumio volumio[2254]: info: Discovery: Getting this device information Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:07 volumio volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 21:09:07 volumio volumio-remote-updater[852]: No test mode Apr 25 21:09:07 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:07 volumio volumio[2254]: info: Retrieving Cloud Streaming UI Apr 25 21:09:07 volumio volumio[2254]: info: Getting Tidal Cloud Configuration Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:07 volumio volumio[2254]: info: Getting Qobuz Cloud Configuration Apr 25 21:09:07 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:07 volumio volumio[2254]: info: Getting Spotify Cloud Configuration Apr 25 21:09:07 volumio volumio[2254]: info: Asking plugin for UI Config Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:07 volumio volumio[2254]: info: Saving Spotify Acccount Apr 25 21:09:07 volumio volumio[2254]: info: Got Tidal Cloud Configuration Apr 25 21:09:07 volumio volumio[2254]: info: Got it Apr 25 21:09:07 volumio volumio[2254]: info: Got it Apr 25 21:09:07 volumio volumio[2254]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:09:07 volumio volumio[2254]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 25 21:09:07 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:09:07 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:09:08 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 25 21:09:08 volumio volumio[2254]: info: Loading i18n strings for locale en Apr 25 21:09:08 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 25 21:09:08 volumio volumio[2254]: info: Setting timezone to UTC Apr 25 21:09:08 volumio sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 25 21:09:08 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:08 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:08 volumio sudo[2798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Apr 25 21:09:08 volumio sudo[2798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:08 volumio sudo[2798]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:08 volumio sudo[2802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 25 21:09:08 volumio sudo[2802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:08 volumio sudo[2802]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:08 volumio sudo[2806]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 25 21:09:08 volumio sudo[2806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:08 volumio sudo[2806]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:08 volumio volumio[2254]: Updating browse sources language Apr 25 21:09:08 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 21:09:08 volumio volumio[2254]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.124 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Apr 25 21:09:08 volumio volumio[2254]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 21:09:08 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 21:09:08 volumio volumio[2254]: info: Fetching Streaming Services browse cache Apr 25 21:09:09 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 21:09:09 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 21:09:09 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 25 21:09:09 volumio volumio[2254]: info: MyVolumio login type: Token Apr 25 21:09:10 volumio volumio[2254]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 25 21:09:10 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 21:09:10 volumio volumio[2254]: info: MyVolumio token set successfully Apr 25 21:09:10 volumio volumio[2254]: info: MYVOLUMIO: Adding device Apr 25 21:09:10 volumio volumio[2254]: info: MYVOLUMIO: Evaluating Server Apr 25 21:09:10 volumio volumio[2254]: info: MyVolumio Plan changed: superstar Apr 25 21:09:10 volumio volumio[2254]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 25 21:09:10 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:10 volumio volumio[2254]: info: MYVOLUMIO: Adding device Apr 25 21:09:10 volumio volumio[2254]: info: MYVOLUMIO: Evaluating Server Apr 25 21:09:10 volumio volumio[2254]: info: Remote config written successfully Apr 25 21:09:10 volumio volumio[2254]: info: Starting Tunnel 1 Apr 25 21:09:10 volumio volumio[2254]: info: Starting Tunnel Connection Checker Apr 25 21:09:10 volumio volumio[2254]: info: MYVolumio Device enabled Apr 25 21:09:10 volumio volumio[2254]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 25 21:09:10 volumio volumio[2254]: info: MyVolumio status changed Apr 25 21:09:10 volumio volumio[2254]: info: Streaming services startup Apr 25 21:09:10 volumio volumio[2254]: info: Starting Streaming Daemon Apr 25 21:09:10 volumio sudo[2841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 25 21:09:10 volumio sudo[2841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:10 volumio volumio[2254]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Apr 25 21:09:10 volumio sudo[2841]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:10 volumio volumio[2254]: error: Cannot start Volumio Streaming Daemon Apr 25 21:09:10 volumio volumio[2254]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 25 21:09:10 volumio volumio[2254]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 25 21:09:11 volumio volumio[2254]: info: Setting Geolocation for MyVolumio to eu7 Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: Setting Geolocation for MyVolumio to eu6 Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:11 volumio volumio[2254]: info: Successfully Added MyVolumio device Apr 25 21:09:11 volumio volumio[2254]: info: Successfully Added MyVolumio device Apr 25 21:09:11 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 25 21:09:11 volumio volumio[2254]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 25 21:09:11 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 25 21:09:11 volumio sudo[2845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 25 21:09:11 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:11 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:11 volumio volumio[2254]: info: MRS: MultiRoom plugin initialized Apr 25 21:09:11 volumio volumio[2254]: info: MRS: STOPPING SNAPCLIENT Apr 25 21:09:11 volumio volumio[2254]: info: MRS: Snap server stop Apr 25 21:09:11 volumio volumio[2254]: info: MRS: STOPPING volumioStreaming Apr 25 21:09:11 volumio sudo[2862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 25 21:09:11 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:11 volumio sudo[2864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 25 21:09:11 volumio sudo[2864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 25 21:09:12 volumio sudo[2867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 25 21:09:12 volumio sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 25 21:09:12 volumio sudo[2870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2870]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 25 21:09:12 volumio sudo[2862]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio sudo[2867]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio sudo[2864]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 25 21:09:12 volumio volumio[2254]: info: Preparing to generate the ALSA configuration file Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Removed streaming files Apr 25 21:09:12 volumio volumio[2254]: info: MRS: volumioStreaming STOPPED Apr 25 21:09:12 volumio volumio[2254]: info: MRS: SNAPSERVER STOPPED Apr 25 21:09:12 volumio volumio[2254]: info: MRS: SNAPCLIENT STOPPED Apr 25 21:09:12 volumio volumio[2254]: info: Updating MyVolumio device info Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: Asound.conf file unchanged, so no further update is needed Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting MPD Apr 25 21:09:12 volumio sudo[2875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 21:09:12 volumio sudo[2875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting Shairport Sync Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio sudo[2877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 21:09:12 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2875]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 21:09:12 volumio volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: [FUNC] onStart Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Apr 25 21:09:12 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 21:09:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Apr 25 21:09:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 21:09:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output for this device Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio bluetoothd[845]: Path / reserved for Adv Monitor app :1.42 Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 25 21:09:12 volumio bluetoothd[845]: Adv Monitor app :1.42 disconnected from D-Bus Apr 25 21:09:12 volumio volumio[2254]: info: Adding METAVOLUMIO REST API Endpoints Apr 25 21:09:12 volumio volumio[2254]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 25 21:09:12 volumio volumio[2254]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 25 21:09:12 volumio volumio[2254]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 25 21:09:12 volumio volumio[2254]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 25 21:09:12 volumio volumio[2254]: info: Preparing CD Folders Apr 25 21:09:12 volumio volumio[2254]: info: Adding CD REST API Endpoints Apr 25 21:09:12 volumio volumio[2254]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 25 21:09:12 volumio volumio[2254]: info: Starting UDEV Watcher for CD Apr 25 21:09:12 volumio volumio[2254]: info: Detecting CD presence with UDEV Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 25 21:09:12 volumio volumio[2254]: info: Adding inputs REST Endpoints Apr 25 21:09:12 volumio volumio[2254]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 25 21:09:12 volumio volumio[2254]: info: Scanning Audio Inputs Apr 25 21:09:12 volumio volumio[2254]: info: Checking against Known Cards name Apr 25 21:09:12 volumio volumio[2254]: info: Checking against Known Cards name Apr 25 21:09:12 volumio volumio[2254]: info: Adding Server instance for streaming Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 25 21:09:12 volumio volumio[2254]: error: Hi Res Audio Failed Login: Missing Login Data Apr 25 21:09:12 volumio volumio[2254]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 25 21:09:12 volumio volumio[2254]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 25 21:09:12 volumio sudo[2886]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 21:09:12 volumio sudo[2886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 21:09:12 volumio sudo[2886]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: Stopping AccessToken refresher cron Apr 25 21:09:12 volumio sudo[2898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:09:12 volumio volumio[2254]: info: AccessToken refresher cron started Apr 25 21:09:12 volumio volumio[2254]: info: Stopping AccessToken refresher cron for QOBUZ Apr 25 21:09:12 volumio volumio[2254]: info: AccessToken refresher cron started for QOBUZ Apr 25 21:09:12 volumio volumio[2254]: info: Adding TIDAL REST API Endpoints Apr 25 21:09:12 volumio volumio[2254]: info: Adding QOBUZ REST API Endpoints Apr 25 21:09:12 volumio sudo[2898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio volumio[2254]: info: Updating MyVolumio device info Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio sudo[2898]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Adding multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Adding multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Adding multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Apr 25 21:09:12 volumio volumio[2254]: info: MPD Permissions set Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Adding multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: Adding audio output: Apr 25 21:09:12 volumio sudo[2903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 25 21:09:12 volumio sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio sudo[2903]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumiobt[2914]: INFO [BTSTART] Ensuring Bluetooth directory exists... Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::volumioStop Apr 25 21:09:12 volumio volumio[2254]: info: CoreStateMachine::stop Apr 25 21:09:12 volumio volumio[2254]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 21:09:12 volumio volumio[2254]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Apr 25 21:09:12 volumio volumio[2254]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 21:09:12 volumio volumio[2254]: info: Starting Shairport Sync Apr 25 21:09:12 volumio sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Apr 25 21:09:12 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: Preparing to generate the ALSA configuration file Apr 25 21:09:12 volumio sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Apr 25 21:09:12 volumio volumio[2254]: info: Asound.conf file unchanged, so no further update is needed Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting MPD Apr 25 21:09:12 volumio sudo[2919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 21:09:12 volumio sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2918]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumiobt[2923]: INFO [BTSTART] Powering on Bluetooth if needed... Apr 25 21:09:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 21:09:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio systemd[1]: shairport-sync.service: Consumed 1.663s CPU time. Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting Shairport Sync Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio bluetoothd[845]: Adv Monitor app :1.44 disconnected from D-Bus Apr 25 21:09:12 volumio sudo[2928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 21:09:12 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: Preparing to generate the ALSA configuration file Apr 25 21:09:12 volumio volumio[2254]: info: Successfully Updated MyVolumio device Apr 25 21:09:12 volumio volumio[2254]: info: MPD Permissions set Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio volumiobt[2936]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Apr 25 21:09:12 volumio sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 21:09:12 volumio volumiobt[2937]: [75B blob data] Apr 25 21:09:12 volumio volumiobt[2937]: [bluetooth]# pairable on Apr 25 21:09:12 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 21:09:12 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio bluetoothd[845]: Adv Monitor app :1.45 disconnected from D-Bus Apr 25 21:09:12 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 21:09:12 volumio volumiobt[2937]: [bluetooth]# Apr 25 21:09:12 volumio volumiobt[2947]: INFO [BTSTART] Registering Bluetooth agent... Apr 25 21:09:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 21:09:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 21:09:12 volumio volumio[2254]: info: Asound.conf file unchanged, so no further update is needed Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting MPD Apr 25 21:09:12 volumio bluetoothd[845]: Adv Monitor app :1.48 disconnected from D-Bus Apr 25 21:09:12 volumio volumio[2254]: info: Output device has changed, restarting Shairport Sync Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio sudo[2967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 21:09:12 volumio sudo[2967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 21:09:12 volumio sudo[2968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio sudo[2968]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:09:12 volumio volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumiobt[2969]: No agent is registered Apr 25 21:09:12 volumio bluetoothd[845]: Adv Monitor app :1.49 disconnected from D-Bus Apr 25 21:09:12 volumio volumiobt[2977]: INFO [BTSTART] Agent registered successfully. Apr 25 21:09:12 volumio volumiobt[2978]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 21:09:12 volumio volumio[2254]: info: MPD Permissions set Apr 25 21:09:12 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 25 21:09:12 volumio volumio[2254]: info: Shairport-Sync Started Apr 25 21:09:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 21:09:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio volumio[2254]: info: Starting Shairport Sync Apr 25 21:09:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 21:09:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 21:09:12 volumio sudo[2983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 21:09:12 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 21:09:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 21:09:12 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:09:12 volumio volumio[2254]: info: Shairport-Sync Started Apr 25 21:09:12 volumio volumio[2254]: info: Successfully Updated MyVolumio device Apr 25 21:09:12 volumio volumio[2254]: info: Starting Shairport Sync Apr 25 21:09:12 volumio sudo[2982]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 21:09:12 volumio sudo[2982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 21:09:12 volumio sudo[2982]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio sudo[3008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 21:09:12 volumio sudo[3008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 21:09:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 21:09:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:09:12 volumio sudo[3008]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: info: Shairport-Sync Started Apr 25 21:09:12 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 21:09:12 volumio volumiobt[2979]: INFO:a2dp-agent:Connecting to system bus. Apr 25 21:09:12 volumio volumiobt[2979]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Apr 25 21:09:12 volumio volumiobt[2979]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Apr 25 21:09:12 volumio volumiobt[2979]: INFO:a2dp-agent:Registering agent. Apr 25 21:09:12 volumio volumiobt[2979]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Apr 25 21:09:12 volumio volumiobt[2979]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Apr 25 21:09:12 volumio volumio[2254]: info: BOOT COMPLETED Apr 25 21:09:12 volumio mpd[3011]: 2025-04-25T19:09:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 21:09:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 21:09:12 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio sudo[2967]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio sudo[2928]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:12 volumio volumio[2254]: error: MPD error: The expression evaluated to a falsy value: Apr 25 21:09:12 volumio volumio[2254]: assert.ok(self.idling) Apr 25 21:09:12 volumio volumio[2254]: error: The expression evaluated to a falsy value: Apr 25 21:09:12 volumio volumio[2254]: assert.ok(self.idling) Apr 25 21:09:12 volumio volumio[2254]: error: MPD error: The expression evaluated to a falsy value: Apr 25 21:09:12 volumio volumio[2254]: assert.ok(self.idling) Apr 25 21:09:12 volumio volumio[2254]: error: The expression evaluated to a falsy value: Apr 25 21:09:12 volumio volumio[2254]: assert.ok(self.idling) Apr 25 21:09:12 volumio volumio[2254]: error: updateQueue error: null Apr 25 21:09:14 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 21:09:14 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 21:09:14 volumio volumio-remote-updater[852]: No test mode Apr 25 21:09:14 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:09:14 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 21:09:14 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:09:14 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:09:15 volumio volumio[2254]: info: TidalConnect service stoped! Apr 25 21:09:15 volumio volumio[2254]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:09:15 volumio volumio[2254]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:09:15 volumio sudo[3054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 21:09:15 volumio sudo[3054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:15 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 21:09:15 volumio sudo[3054]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:15 volumio volumio[2254]: info: Executing endpoint tc_getconfig Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 21:09:15 volumio vtcs[3057]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 21:09:15 volumio vtcs[3057]: STARTED TidalConnect services. Apr 25 21:09:15 volumio volumio[2254]: info: Executing endpoint tc_connect Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 21:09:15 volumio volumio[2254]: info: Connecting to TidalConnect Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::servicePushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:09:15 volumio volumio[2254]: 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 21:09:15 volumio volumio[2254]: verbose: CURRENT POSITION 0 Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::syncState stateService stop Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::syncState currentStatus stop Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio volumio[2254]: info: No code Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::servicePushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:09:15 volumio volumio[2254]: 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 21:09:15 volumio volumio[2254]: verbose: CURRENT POSITION 0 Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::syncState stateService stop Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::syncState currentStatus stop Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio volumio[2254]: info: No code Apr 25 21:09:15 volumio volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:09:15 volumio volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:09:15 volumio sudo[3079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 25 21:09:15 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:09:15 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 21:09:15 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 21:09:15 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 25 21:09:15 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root Apr 25 21:09:15 volumio volumio[2254]: info: Remote SSH Started Apr 25 21:09:15 volumio autossh[3082]: port set to 0, monitoring disabled Apr 25 21:09:15 volumio autossh[3082]: starting ssh (count 1) Apr 25 21:09:15 volumio autossh[3082]: ssh child pid is 3085 Apr 25 21:09:15 volumio volumio[2254]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Apr 25 21:09:15 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:15 volumio volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:09:15 volumio volumiossh-tunnel[3085]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 25 21:09:17 volumio volumio[2254]: info: MRS: Getting audio outputs on start Apr 25 21:09:17 volumio volumio[2254]: info: MRS: Requesting all other devices output Apr 25 21:09:18 volumio volumio[2254]: info: TidalConnect service started! Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 21:09:25 volumio volumio[2254]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 25 21:09:25 volumio volumio[2254]: info: Received Get System Version Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 21:09:25 volumio volumio[2254]: info: Received Get System Info Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 21:09:25 volumio volumio[2254]: info: Discovery: Getting this device information Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:25 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 25 21:09:26 volumio volumio[2254]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 25 21:09:26 volumio volumio[2254]: info: Received Get System Version Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 21:09:26 volumio volumio[2254]: info: Received Get System Info Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 21:09:26 volumio volumio[2254]: info: Discovery: Getting this device information Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:09:26 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 21:09:38 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 21:09:38 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 21:09:38 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Apr 25 21:09:38 volumio volumio-remote-updater[852]: No test mode Apr 25 21:09:38 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:09:38 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 21:09:38 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:09:38 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:10:03 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 25 21:10:03 volumio volumio[2254]: info: Setting timezone to Europe/Zurich Apr 25 21:10:03 volumio sudo[3275]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 25 21:10:03 volumio sudo[3275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:03 volumio sudo[3275]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:03 volumio sudo[3279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Zurich /etc/localtime Apr 25 21:10:03 volumio sudo[3279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:03 volumio sudo[3279]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:03 volumio sudo[3283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 25 21:10:03 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:04 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:04 volumio sudo[3287]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Zurich Apr 25 21:10:04 volumio sudo[3287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:04 volumio dbus-daemon[846]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.57' (uid=0 pid=3288 comm="/usr/bin/timedatectl set-timezone Europe/Zurich") Apr 25 21:10:04 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Apr 25 21:10:04 volumio dbus-daemon[846]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 25 21:10:04 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Apr 25 21:10:04 volumio sudo[3287]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 25 21:10:05 volumio volumio[2254]: info: Loading i18n strings for locale en Apr 25 21:10:05 volumio volumio[2254]: Updating browse sources language Apr 25 21:10:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 21:10:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 25 21:10:05 volumio volumio[2254]: info: Setting timezone to Europe/Zurich Apr 25 21:10:05 volumio sudo[3306]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 25 21:10:05 volumio sudo[3306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:05 volumio sudo[3306]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio sudo[3310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Zurich /etc/localtime Apr 25 21:10:05 volumio sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:05 volumio sudo[3310]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio sudo[3314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 25 21:10:05 volumio sudo[3314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:05 volumio sudo[3314]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio sudo[3318]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Zurich Apr 25 21:10:05 volumio sudo[3318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:05 volumio sudo[3318]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:05 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:05 volumio sudo[3321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:05 volumio sudo[3321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:05 volumio sudo[3321]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:05 volumio volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:05 volumio volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:05 volumio volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:05 volumio sudo[3325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:05 volumio sudo[3325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:06 volumio sudo[3325]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:06 volumio volumio[2254]: info: Fetching Streaming Services browse cache Apr 25 21:10:34 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 21:10:34 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 21:10:34 volumio volumio-remote-updater[852]: No test mode Apr 25 21:10:34 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:10:34 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 21:10:34 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:10:34 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:10:35 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Apr 25 21:10:35 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 25 21:10:35 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Apr 25 21:10:35 volumio volumio-remote-updater[852]: No test mode Apr 25 21:10:35 volumio volumio-remote-updater[852]: No alpha test mode Apr 25 21:10:35 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 25 21:10:35 volumio volumio[2254]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 25 21:10:35 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 25 21:10:46 volumio volumio[2254]: info: System name has changed, restarting Shairport Sync Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:46 volumio volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:10:46 volumio volumio[2254]: info: Updating MyVolumio device info Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:46 volumio vtcs[3057]: [2025-04-25 21:10:46.666] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 21:10:46 volumio vtcs[3057]: [2025-04-25 21:10:46.666] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 21:10:46 volumio volumio[2254]: info: Discovery: Restarting Advertising due to device name change Apr 25 21:10:46 volumio sudo[3397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:46 volumio sudo[3397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 volumio sudo[3399]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 25 21:10:46 volumio sudo[3399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 25 21:10:46 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 21:10:46 volumio sudo[3399]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 volumio sudo[3402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:46 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 volumio systemd[1]: vtcs.service: Deactivated successfully. Apr 25 21:10:46 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 21:10:46 volumio sudo[3397]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:46 volumio volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 21:10:46 volumio sudo[3402]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 volumio volumio[2254]: info: Starting Shairport Sync Apr 25 21:10:46 volumio sudo[3411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 25 21:10:46 volumio sudo[3411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 volumio sudo[3413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 21:10:46 volumio sudo[3413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 volumio sudo[3411]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 volumio volumio[2254]: info: Permissions for /etc/hosts set Apr 25 21:10:46 volumio volumio[2254]: info: Hostname now is hp-note Apr 25 21:10:46 volumio sudo[3418]: volumio : unable to resolve host volumio: System error Apr 25 21:10:46 hp-note volumio[2254]: info: New hostname set Apr 25 21:10:46 hp-note volumio[2254]: info: Permissions for /etc/avahi/services/volumio.service Apr 25 21:10:46 hp-note sudo[3418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname hp-note Apr 25 21:10:46 hp-note sudo[3418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 hp-note systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Apr 25 21:10:46 hp-note sudo[3418]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 hp-note sudo[3420]: volumio : unable to resolve host volumio: System error Apr 25 21:10:46 hp-note sudo[3420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Apr 25 21:10:46 hp-note sudo[3420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:46 hp-note sudo[3420]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 hp-note avahi-daemon[844]: Files changed, reloading. Apr 25 21:10:46 hp-note volumio[2254]: info: Avahi name changed to hp-note Apr 25 21:10:46 hp-note systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 25 21:10:46 hp-note avahi-daemon[844]: Loading service file /services/volumio.service. Apr 25 21:10:46 hp-note systemd[1]: shairport-sync.service: Deactivated successfully. Apr 25 21:10:46 hp-note systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:10:46 hp-note systemd[1]: shairport-sync.service: Consumed 1.950s CPU time. Apr 25 21:10:46 hp-note systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 25 21:10:46 hp-note systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Apr 25 21:10:46 hp-note sudo[3413]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:46 hp-note systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Apr 25 21:10:46 hp-note volumio[2254]: info: Shairport-Sync Started Apr 25 21:10:47 hp-note volumio[2254]: info: Successfully Updated MyVolumio device Apr 25 21:10:47 hp-note avahi-daemon[844]: Service "HP-Note" (/services/volumio.service) successfully established. Apr 25 21:10:47 hp-note volumio[2254]: info: Discovery: A device disappeared from network Apr 25 21:10:47 hp-note volumio[2254]: info: Discovery: Device volumio disappeared from network Apr 25 21:10:47 hp-note volumio[2254]: info: Discovery: A device disappeared from network Apr 25 21:10:47 hp-note volumio[2254]: info: Discovery: A device disappeared from network Apr 25 21:10:49 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:49 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:49 hp-note volumio[2254]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:49 hp-note volumio[2254]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:49 hp-note sudo[3451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 21:10:49 hp-note sudo[3451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:49 hp-note systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 21:10:49 hp-note sudo[3451]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:49 hp-note volumio[2254]: info: Executing endpoint tc_getconfig Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 21:10:49 hp-note vtcs[3454]: STARTING TidalConnect services, version: 1.5.0.45 Apr 25 21:10:49 hp-note vtcs[3454]: STARTED TidalConnect services. Apr 25 21:10:49 hp-note volumio[2254]: info: Executing endpoint tc_connect Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 21:10:49 hp-note volumio[2254]: info: Connecting to TidalConnect Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::servicePushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:10:49 hp-note volumio[2254]: 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 21:10:49 hp-note volumio[2254]: verbose: CURRENT POSITION 0 Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::syncState stateService stop Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::syncState currentStatus stop Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: info: No code Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::servicePushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: info: CorePlayQueue::getTrack 0 Apr 25 21:10:49 hp-note volumio[2254]: 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 21:10:49 hp-note volumio[2254]: verbose: CURRENT POSITION 0 Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::syncState stateService stop Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::syncState currentStatus stop Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: info: No code Apr 25 21:10:49 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:49 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:49 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:49 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 25 21:10:51 hp-note volumio[2254]: info: Preparing to save Alsa Options, stopping services first Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:10:51 hp-note volumio[2254]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1,0","label":"Analog Out"},"disallowPush":true} Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 21:10:51 hp-note volumio[2254]: info: Found match in Cards Database: setting mixer for card Analog Out Apr 25 21:10:51 hp-note vtcs[3454]: [2025-04-25 21:10:51.446] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 25 21:10:51 hp-note vtcs[3454]: [2025-04-25 21:10:51.447] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 25 21:10:51 hp-note sudo[3477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:51 hp-note sudo[3477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note volumio[2254]: info: Setting mixer Master for card Analog Out Apr 25 21:10:51 hp-note systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 25 21:10:51 hp-note systemd[1]: vtcs.service: Deactivated successfully. Apr 25 21:10:51 hp-note systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 25 21:10:51 hp-note sudo[3483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:51 hp-note sudo[3483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 25 21:10:51 hp-note volumio[2254]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 25 21:10:51 hp-note volumio[2254]: info: Disabling external Volume Control Apr 25 21:10:51 hp-note sudo[3477]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:10:51 hp-note volumio[2254]: info: Preparing to generate the ALSA configuration file Apr 25 21:10:51 hp-note sudo[3483]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:51 hp-note sudo[3491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:51 hp-note sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:51 hp-note sudo[3491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:51 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:51 hp-note volumio[2254]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 21:10:51 hp-note volumio[2254]: info: Reading ALSA contributions from plugins. Apr 25 21:10:51 hp-note sudo[3498]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:51 hp-note sudo[3498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note sudo[3498]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:51 hp-note sudo[3500]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:51 hp-note sudo[3500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note sudo[3500]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:51 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:51 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:51 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:51 hp-note sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:51 hp-note sudo[3494]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:51 hp-note sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:51 hp-note sudo[3491]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:52 hp-note sudo[3507]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:52 hp-note volumio[2254]: info: Discovery: Started advertising with name: HP-Note Apr 25 21:10:52 hp-note volumio[2254]: info: VolumeController:: Volume=41 Mute =false Apr 25 21:10:52 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:52 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 21:10:52 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:52 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:52 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:52 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:52 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:52 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:52 hp-note sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:52 hp-note sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:53 hp-note sudo[3514]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:53 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:53 hp-note volumio[2254]: info: TidalConnect service started! Apr 25 21:10:53 hp-note sudo[3523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:53 hp-note volumio[2254]: verbose: New Socket.io Connection to 192.168.0.128:3000 from 192.168.0.153 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 25 21:10:53 hp-note sudo[3525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:53 hp-note sudo[3523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:53 hp-note sudo[3525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:53 hp-note sudo[3525]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:53 hp-note sudo[3523]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:53 hp-note volumio[2254]: verbose: New Socket.io Connection to 192.168.0.128:3000 from 192.168.0.156 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 25 21:10:53 hp-note volumio[2254]: verbose: New Socket.io Connection to 192.168.0.128:3000 from 192.168.0.140 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 25 21:10:53 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:53 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:53 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:53 hp-note sudo[3532]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:53 hp-note sudo[3532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:54 hp-note sudo[3532]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:54 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:54 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:54 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:54 hp-note sudo[3536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:54 hp-note sudo[3536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:55 hp-note sudo[3536]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 25 21:10:55 hp-note volumio[2254]: info: CoreStateMachine::getcurrentVolume Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:55 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:55 hp-note sudo[3555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:55 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:55 hp-note sudo[3557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:55 hp-note sudo[3555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:55 hp-note sudo[3557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:55 hp-note sudo[3555]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:55 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:55 hp-note volumio[2254]: info: TidalConnect service stoped! Apr 25 21:10:55 hp-note sudo[3557]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:55 hp-note volumio[2254]: info: Discovery: adding 5707a9de-f5cb-4e51-96ee-f5a439a87c32 Apr 25 21:10:55 hp-note volumio[2254]: info: Discovery: Found device HP-Note Apr 25 21:10:55 hp-note volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:10:55 hp-note volumio[2254]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:55 hp-note volumio[2254]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:55 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:55 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:55 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:55 hp-note sudo[3579]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:55 hp-note sudo[3579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:56 hp-note sudo[3579]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:56 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:56 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:56 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:56 hp-note sudo[3583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:56 hp-note sudo[3583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3583]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Apr 25 21:10:57 hp-note sudo[3586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Apr 25 21:10:57 hp-note sudo[3588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note volumio[2254]: info: Discovery: this is already registered, 5707a9de-f5cb-4e51-96ee-f5a439a87c32 Apr 25 21:10:57 hp-note sudo[3591]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 21:10:57 hp-note volumio[2254]: info: Discovery: Found device HP-Note Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:10:57 hp-note sudo[3591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3588]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3594]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 21:10:57 hp-note sudo[3594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:10:57 hp-note volumio[2254]: info: Retrieving Cloud Streaming UI Apr 25 21:10:57 hp-note sudo[3594]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:57 hp-note volumio[2254]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 21:10:57 hp-note sudo[3591]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: VolumeController:: Volume=41 Mute =false Apr 25 21:10:57 hp-note volumio[2254]: info: CoreStateMachine::pushState Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::volumioPushState Apr 25 21:10:57 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output update for this device Apr 25 21:10:57 hp-note sudo[3586]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: MRS: Pushing multiroomSync output Apr 25 21:10:57 hp-note volumio[2254]: info: CoreStateMachine::updateTrackBlock Apr 25 21:10:57 hp-note volumio[2254]: info: CorePlayQueue::getTrackBlock Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 21:10:57 hp-note volumio[2254]: info: Asound.conf file written Apr 25 21:10:57 hp-note sudo[3600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 21:10:57 hp-note sudo[3600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3600]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: No state is present for card HDMI Apr 25 21:10:57 hp-note volumio[2254]: Found hardware: "HDA-Intel" "Intel Broadwell HDMI" "HDA:80862808,80860101,00100000" "0x103c" "0x2336" Apr 25 21:10:57 hp-note volumio[2254]: Hardware is initialized using a generic method Apr 25 21:10:57 hp-note volumio[2254]: No state is present for card HDMI Apr 25 21:10:57 hp-note volumio[2254]: No state is present for card PCH Apr 25 21:10:57 hp-note volumio[2254]: Found hardware: "HDA-Intel" "Realtek ALC3227" "HDA:10ec0282,103c2336,00100003" "0x103c" "0x2336" Apr 25 21:10:57 hp-note volumio[2254]: Hardware is initialized using a generic method Apr 25 21:10:57 hp-note volumio[2254]: No state is present for card PCH Apr 25 21:10:57 hp-note volumio[2254]: info: Output device has changed, restarting MPD Apr 25 21:10:57 hp-note volumio[2254]: info: Output device has changed, restarting Shairport Sync Apr 25 21:10:57 hp-note sudo[3608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:57 hp-note sudo[3606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 21:10:57 hp-note sudo[3606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3606]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:10:57 hp-note volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:10:57 hp-note systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 25 21:10:57 hp-note volumio[2254]: info: Output device has changed, restarting MPD Apr 25 21:10:57 hp-note systemd[1]: mpd.service: Deactivated successfully. Apr 25 21:10:57 hp-note systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 21:10:57 hp-note systemd[1]: mpd.socket: Deactivated successfully. Apr 25 21:10:57 hp-note systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 21:10:57 hp-note sudo[3618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:57 hp-note systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 21:10:57 hp-note sudo[3618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note volumio[2254]: info: Output device has changed, restarting Shairport Sync Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 21:10:57 hp-note systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 21:10:57 hp-note sudo[3620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 21:10:57 hp-note systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 21:10:57 hp-note sudo[3620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3620]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 21:10:57 hp-note sudo[3624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note volumio[2254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 21:10:57 hp-note volumio[2254]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 21:10:57 hp-note systemd[1]: mpd.service: Deactivated successfully. Apr 25 21:10:57 hp-note systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 25 21:10:57 hp-note systemd[1]: mpd.socket: Deactivated successfully. Apr 25 21:10:57 hp-note systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 25 21:10:57 hp-note systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 25 21:10:57 hp-note systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 25 21:10:57 hp-note systemd[1]: Starting mpd.service - Music Player Daemon... Apr 25 21:10:57 hp-note volumio[2254]: info: Discovery: this is already registered, 5707a9de-f5cb-4e51-96ee-f5a439a87c32 Apr 25 21:10:57 hp-note sudo[3618]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: Discovery: Found device HP-Note Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:10:57 hp-note sudo[3635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:57 hp-note sudo[3635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:57 hp-note sudo[3637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:57 hp-note sudo[3639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 21:10:57 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 21:10:57 hp-note sudo[3645]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:57 hp-note sudo[3645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3637]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3645]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.62DrhCDTP9Z3P0h2i0ZsGKnKIMh1.bce017ffbed236e57d804fffc67926ce.state.status' Apr 25 21:10:57 hp-note sudo[3634]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 21:10:57 hp-note sudo[3634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 25 21:10:57 hp-note sudo[3648]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 25 21:10:57 hp-note sudo[3648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3634]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3648]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note volumio[2254]: info: smb.conf Permissions set Apr 25 21:10:57 hp-note volumio[2254]: info: Internal perms successfully set Apr 25 21:10:57 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:57 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:57 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:57 hp-note sudo[3654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 21:10:57 hp-note sudo[3654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:57 hp-note sudo[3658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:57 hp-note sudo[3635]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3639]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 25 21:10:57 hp-note sudo[3654]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note mpd[3652]: 2025-04-25T21:10:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 21:10:57 hp-note systemd[1]: Started mpd.service - Music Player Daemon. Apr 25 21:10:57 hp-note sudo[3608]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:57 hp-note sudo[3624]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:58 hp-note sudo[3658]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:58 hp-note volumio[2254]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 25 21:10:58 hp-note volumio[2254]: wlan0 Interface doesn't support scanning : Operation not supported Apr 25 21:10:58 hp-note volumio[2254]: info: Cannot use regular scanning, forcing with ap-force Apr 25 21:10:58 hp-note sudo[3668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 25 21:10:58 hp-note sudo[3668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:59 hp-note sudo[3668]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:59 hp-note volumio[2254]: info: MPD Permissions set Apr 25 21:10:59 hp-note volumio[2254]: info: MPD Permissions set Apr 25 21:10:59 hp-note volumio[2254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 25 21:10:59 hp-note volumio[2254]: info: CoreCommandRouter::volumioGetState Apr 25 21:10:59 hp-note sudo[3672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 21:10:59 hp-note sudo[3672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 25 21:10:59 hp-note volumio[2254]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 21:10:59 hp-note volumio[2254]: info: Retrieving Cloud Streaming UI Apr 25 21:10:59 hp-note volumio[2254]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 25 21:10:59 hp-note volumio[2254]: Job for vtcs.service canceled. Apr 25 21:10:59 hp-note volumio[2254]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\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)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Apr 25 21:10:59 hp-note volumio[2254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 21:10:59 hp-note volumio[2254]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 25 21:10:59 hp-note volumio[2254]: Job for vtcs.service canceled. Apr 25 21:10:59 hp-note volumio[2254]: at ChildProcess.exithandler (node:child_process:421:12) Apr 25 21:10:59 hp-note volumio[2254]: at ChildProcess.emit (node:events:514:28) Apr 25 21:10:59 hp-note volumio[2254]: at maybeClose (node:internal/child_process:1105:16) Apr 25 21:10:59 hp-note volumio[2254]: at Socket. (node:internal/child_process:457:11) Apr 25 21:10:59 hp-note volumio[2254]: at Socket.emit (node:events:514:28) Apr 25 21:10:59 hp-note volumio[2254]: at Pipe. (node:net:337:12) { Apr 25 21:10:59 hp-note volumio[2254]: code: 1, Apr 25 21:10:59 hp-note volumio[2254]: killed: false, Apr 25 21:10:59 hp-note volumio[2254]: signal: null, Apr 25 21:10:59 hp-note volumio[2254]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Apr 25 21:10:59 hp-note volumio[2254]: stdout: '', Apr 25 21:10:59 hp-note volumio[2254]: stderr: 'Job for vtcs.service canceled.\n' Apr 25 21:10:59 hp-note volumio[2254]: } Apr 25 21:10:59 hp-note volumio[2254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 21:10:59 hp-note sudo[3672]: pam_unix(sudo:session): session closed for user root Apr 25 21:10:59 hp-note sudo[3690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-25 21:09' Apr 25 21:10:59 hp-note sudo[3690]: 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"