Feb 07 09:19:00 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 09:19:00 raspberry volumio[1008]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 07 09:19:00 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:00 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:03 raspberry systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Feb 07 09:19:03 raspberry systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 09:19:03 raspberry systemd[1]: upmpdcli.service: Consumed 19.104s CPU time. Feb 07 09:19:03 raspberry systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 09:19:03 raspberry sudo[2870]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:03 raspberry volumio[1008]: info: Upmpdcli Daemon Started Feb 07 09:19:04 raspberry upmpdcli[2903]: writing RSA key Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 09:19:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 09:19:28 raspberry sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 09:19:28 raspberry sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:28 raspberry sudo[2943]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 09:19:28 raspberry sudo[2943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:28 raspberry sudo[2943]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:28 raspberry sudo[2941]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:28 raspberry volumio[1008]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.130 UA: Mozilla/5.0 (X11; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 07 09:19:28 raspberry sudo[2953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 09:19:28 raspberry sudo[2953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:28 raspberry sudo[2955]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 09:19:28 raspberry sudo[2955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:28 raspberry sudo[2955]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:28 raspberry sudo[2953]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:29 raspberry volumio[1008]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.130 UA: Mozilla/5.0 (X11; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:29 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 07 09:19:29 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:29 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:29 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:29 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:29 raspberry volumio[1008]: info: Listing playlists Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 09:19:29 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:30 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:30 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:30 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:30 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:30 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:31 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 07 09:19:31 raspberry volumio[1008]: info: MyVolumio login type: Token Feb 07 09:19:31 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:31 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:31 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:32 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:32 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:32 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:32 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:32 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:32 raspberry volumio[1008]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 07 09:19:32 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 07 09:19:32 raspberry volumio[1008]: info: MyVolumio login type: Token Feb 07 09:19:32 raspberry volumio[1008]: info: MyVolumio token set successfully Feb 07 09:19:32 raspberry volumio[1008]: info: MYVOLUMIO: Adding device Feb 07 09:19:32 raspberry volumio[1008]: info: MYVOLUMIO: Evaluating Server Feb 07 09:19:33 raspberry volumio[1008]: info: MyVolumio Plan changed: superstar Feb 07 09:19:33 raspberry volumio[1008]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Feb 07 09:19:33 raspberry volumio[1008]: info: Adding audio output: Feb 07 09:19:33 raspberry volumio[1008]: info: MYVOLUMIO: Adding device Feb 07 09:19:33 raspberry volumio[1008]: info: MYVOLUMIO: Evaluating Server Feb 07 09:19:33 raspberry volumio[1008]: info: Remote config written successfully Feb 07 09:19:33 raspberry volumio[1008]: info: Starting Tunnel 1 Feb 07 09:19:33 raspberry volumio[1008]: info: Starting Tunnel Connection Checker Feb 07 09:19:33 raspberry volumio[1008]: info: MYVolumio Device disabled Feb 07 09:19:33 raspberry volumio[1008]: info: MyVolumio status changed Feb 07 09:19:33 raspberry volumio[1008]: info: Streaming services startup Feb 07 09:19:33 raspberry volumio[1008]: info: Starting Streaming Daemon Feb 07 09:19:33 raspberry volumio[1008]: info: Setting Geolocation for MyVolumio to eu7 Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry sudo[3000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 09:19:33 raspberry sudo[3000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:33 raspberry sudo[3000]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:33 raspberry volumio[1008]: error: Cannot start Volumio Streaming Daemon Feb 07 09:19:33 raspberry volumio[1008]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 09:19:33 raspberry volumio[1008]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 09:19:33 raspberry volumio[1008]: info: Setting Geolocation for MyVolumio to eu6 Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:33 raspberry volumio[1008]: info: MYVolumio Device enabled Feb 07 09:19:33 raspberry volumio[1008]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 07 09:19:33 raspberry volumio[1008]: info: MyVolumio status changed Feb 07 09:19:33 raspberry volumio[1008]: info: Streaming services startup Feb 07 09:19:33 raspberry volumio[1008]: info: Starting Streaming Daemon Feb 07 09:19:33 raspberry volumio[1008]: info: Successfully Added MyVolumio device Feb 07 09:19:33 raspberry sudo[3003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 09:19:33 raspberry sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:33 raspberry volumio[1008]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Feb 07 09:19:33 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Feb 07 09:19:33 raspberry sudo[3003]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:33 raspberry volumio[1008]: error: Cannot start Volumio Streaming Daemon Feb 07 09:19:33 raspberry volumio[1008]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 09:19:33 raspberry volumio[1008]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 09:19:34 raspberry volumio[1008]: info: Successfully Added MyVolumio device Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:34 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:34 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:34 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:34 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:34 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:34 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:34 raspberry volumio[1008]: info: Updating MyVolumio device info Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:34 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:34 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:34 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:34 raspberry volumio[1008]: info: Updating MyVolumio device info Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:34 raspberry volumio[1008]: info: Successfully Updated MyVolumio device Feb 07 09:19:35 raspberry volumio[1008]: info: Successfully Updated MyVolumio device Feb 07 09:19:35 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 07 09:19:35 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 07 09:19:35 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 07 09:19:38 raspberry sudo[3025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 07 09:19:38 raspberry sudo[3025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:38 raspberry sudo[3025]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:38 raspberry volumio[1008]: info: MRS: MultiRoom plugin initialized Feb 07 09:19:38 raspberry volumio[1008]: info: MRS: STOPPING SNAPCLIENT Feb 07 09:19:38 raspberry volumio[1008]: info: MRS: Snap server stop Feb 07 09:19:38 raspberry volumio[1008]: info: MRS: STOPPING volumioStreaming Feb 07 09:19:38 raspberry sudo[3042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 07 09:19:38 raspberry sudo[3042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:38 raspberry sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 07 09:19:38 raspberry sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:38 raspberry sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 07 09:19:38 raspberry sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 07 09:19:38 raspberry sudo[3049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 07 09:19:38 raspberry sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:38 raspberry sudo[3049]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 07 09:19:38 raspberry sudo[3042]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:38 raspberry sudo[3046]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:38 raspberry sudo[3044]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 07 09:19:38 raspberry volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 07 09:19:38 raspberry volumio[1008]: info: Preparing to generate the ALSA configuration file Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 09:19:39 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:39 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:39 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: Removed streaming files Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: volumioStreaming STOPPED Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: SNAPSERVER STOPPED Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: SNAPCLIENT STOPPED Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 07 09:19:39 raspberry volumio[1008]: info: Asound.conf file unchanged, so no further update is needed Feb 07 09:19:39 raspberry volumio[1008]: info: Output device has changed, restarting MPD Feb 07 09:19:39 raspberry volumio[1008]: info: Output device has changed, restarting Shairport Sync Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:39 raspberry sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 09:19:39 raspberry sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:39 raspberry sudo[3059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 09:19:39 raspberry sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:39 raspberry systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 09:19:39 raspberry sudo[3057]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:39 raspberry volumio[1008]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:39 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 07 09:19:39 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 07 09:19:39 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 07 09:19:39 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 07 09:19:39 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 07 09:19:39 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 07 09:19:39 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output for this device Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:39 raspberry volumio[1008]: info: Adding audio output: Feb 07 09:19:39 raspberry volumio[1008]: info: Adding audio output: Feb 07 09:19:39 raspberry volumio[1008]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:39 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:39 raspberry volumio[1008]: info: Adding METAVOLUMIO REST API Endpoints Feb 07 09:19:39 raspberry volumio[1008]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 07 09:19:39 raspberry volumio[1008]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 07 09:19:39 raspberry volumio[1008]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 07 09:19:39 raspberry volumio[1008]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 07 09:19:39 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 07 09:19:39 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 07 09:19:39 raspberry volumio[1008]: info: Preparing CD Folders Feb 07 09:19:39 raspberry volumio[1008]: info: Adding CD REST API Endpoints Feb 07 09:19:39 raspberry volumio[1008]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 07 09:19:39 raspberry volumio[1008]: info: Starting UDEV Watcher for CD Feb 07 09:19:39 raspberry volumio[1008]: info: Detecting CD presence with UDEV Feb 07 09:19:39 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 07 09:19:39 raspberry bluetoothd[738]: Path / reserved for Adv Monitor app :1.42 Feb 07 09:19:39 raspberry bluetoothd[738]: Adv Monitor app :1.42 disconnected from D-Bus Feb 07 09:19:43 raspberry systemd[1]: mpd.service: Deactivated successfully. Feb 07 09:19:43 raspberry systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 09:19:43 raspberry systemd[1]: mpd.service: Consumed 5.816s CPU time. Feb 07 09:19:43 raspberry systemd[1]: mpd.socket: Deactivated successfully. Feb 07 09:19:43 raspberry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 09:19:43 raspberry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 09:19:43 raspberry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 09:19:43 raspberry systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 09:19:43 raspberry sudo[3079]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 09:19:43 raspberry sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:19:43 raspberry sudo[3079]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:44 raspberry volumio[1008]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 07 09:19:44 raspberry volumio[1008]: info: Adding inputs REST Endpoints Feb 07 09:19:44 raspberry volumio[1008]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 07 09:19:44 raspberry volumio[1008]: info: Scanning Audio Inputs Feb 07 09:19:44 raspberry volumio[1008]: info: Checking against Known Cards name Feb 07 09:19:44 raspberry volumio[1008]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 09:19:44 raspberry volumio[1008]: info: [1770452384929] CoreMusicLibrary::Adding element C-Media USB Feb 07 09:19:44 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 09:19:44 raspberry volumio[1008]: Cannot find translation for source C-Media USB Feb 07 09:19:44 raspberry volumio[1008]: info: Checking against Known Cards name Feb 07 09:19:44 raspberry volumio[1008]: info: Adding Server instance for streaming Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 07 09:19:44 raspberry volumio[1008]: error: Hi Res Audio Failed Login: Missing Login Data Feb 07 09:19:44 raspberry volumio[1008]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 07 09:19:44 raspberry volumio[1008]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 07 09:19:44 raspberry volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 07 09:19:44 raspberry volumio[1008]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 07 09:19:45 raspberry sudo[3100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 07 09:19:45 raspberry sudo[3100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:45 raspberry volumio[1008]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 07 09:19:45 raspberry volumio[1008]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 07 09:19:45 raspberry volumio[1008]: info: Adding TIDAL REST API Endpoints Feb 07 09:19:45 raspberry volumio[1008]: info: Stopping AccessToken refresher cron for QOBUZ Feb 07 09:19:45 raspberry sudo[3108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 07 09:19:45 raspberry sudo[3108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:45 raspberry sudo[3100]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:45 raspberry sudo[3108]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:45 raspberry sudo[3111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 07 09:19:45 raspberry sudo[3111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:45 raspberry volumio[1008]: info: AccessToken refresher cron started for QOBUZ Feb 07 09:19:45 raspberry volumio[1008]: info: Adding QOBUZ REST API Endpoints Feb 07 09:19:45 raspberry systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 07 09:19:45 raspberry sudo[3111]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:45 raspberry volumio[1008]: info: MRS: Getting audio outputs on start Feb 07 09:19:45 raspberry volumio[1008]: info: MRS: Requesting all other devices output Feb 07 09:19:45 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 07 09:19:45 raspberry volumio[1008]: info: MRS: Adding multiroomSync output Feb 07 09:19:45 raspberry volumio[1008]: info: Adding audio output: Feb 07 09:19:45 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::Close All Modals sent Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Feb 07 09:19:46 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Feb 07 09:19:46 raspberry volumio[1008]: info: MPD Permissions set Feb 07 09:19:46 raspberry sudo[3125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 07 09:19:46 raspberry sudo[3125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 07 09:19:46 raspberry sudo[3130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 07 09:19:46 raspberry sudo[3146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 09:19:46 raspberry sudo[3146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 07 09:19:46 raspberry sudo[3125]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 09:19:46 raspberry sudo[3151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 09:19:46 raspberry sudo[3158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3146]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 07 09:19:46 raspberry sudo[3151]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3158]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Feb 07 09:19:46 raspberry volumio[1008]: info: CoreCommandRouter::volumioStop Feb 07 09:19:46 raspberry volumio[1008]: info: CoreStateMachine::stop Feb 07 09:19:46 raspberry volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 09:19:46 raspberry volumio[1008]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Feb 07 09:19:46 raspberry volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 09:19:46 raspberry volumio[1008]: info: Starting Shairport Sync Feb 07 09:19:46 raspberry sudo[3165]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 09:19:46 raspberry sudo[3165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry 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. Feb 07 09:19:46 raspberry 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. Feb 07 09:19:46 raspberry volumiobt[3168]: INFO [BTSTART] Ensuring Bluetooth directory exists... Feb 07 09:19:46 raspberry sudo[3169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 09:19:46 raspberry sudo[3169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3169]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3142]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 07 09:19:46 raspberry sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3175]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 09:19:46 raspberry sudo[3174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 09:19:46 raspberry sudo[3175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 07 09:19:46 raspberry sudo[3177]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Feb 07 09:19:46 raspberry sudo[3177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3130]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3177]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 09:19:46 raspberry systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 09:19:46 raspberry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:46 raspberry systemd[1]: shairport-sync.service: Consumed 2.169s CPU time. Feb 07 09:19:46 raspberry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:46 raspberry sudo[3174]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3142]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry sudo[3186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Feb 07 09:19:46 raspberry sudo[3186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:46 raspberry sudo[3186]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry volumiobt[3192]: INFO [BTSTART] Powering on Bluetooth if needed... Feb 07 09:19:46 raspberry autossh[3184]: port set to 0, monitoring disabled Feb 07 09:19:46 raspberry autossh[3184]: starting ssh (count 1) Feb 07 09:19:46 raspberry autossh[3184]: ssh child pid is 3196 Feb 07 09:19:46 raspberry sudo[3165]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:46 raspberry bluetoothd[738]: Path / reserved for Adv Monitor app :1.46 Feb 07 09:19:46 raspberry bluetoothd[738]: Adv Monitor app :1.46 disconnected from D-Bus Feb 07 09:19:47 raspberry volumiobt[3211]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Feb 07 09:19:47 raspberry volumio[1008]: info: Executing endpoint qc_getconfig Feb 07 09:19:47 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.220 [3113.3113] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 07 09:19:47 raspberry volumiobt[3212]: [176B blob data] Feb 07 09:19:47 raspberry volumiobt[3212]: [157B blob data] Feb 07 09:19:47 raspberry volumiobt[3212]: [157B blob data] Feb 07 09:19:47 raspberry volumiobt[3212]: [157B blob data] Feb 07 09:19:47 raspberry volumio[1008]: info: MRS: Found cast device: Smart-TV-Pro-2daf9d91706679fb636a309d16bfdfaf Feb 07 09:19:47 raspberry volumiobt[3212]: [113B blob data] Feb 07 09:19:47 raspberry volumiobt[3212]: [bluetoothctl]> discoverable on Feb 07 09:19:47 raspberry volumiobt[3212]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Feb 07 09:19:47 raspberry volumiobt[3212]: [bluetoothctl]> pairable on Feb 07 09:19:47 raspberry bluetoothd[738]: Path / reserved for Adv Monitor app :1.47 Feb 07 09:19:47 raspberry volumio[1008]: info: Adding audio output: Feb 07 09:19:47 raspberry bluetoothd[738]: Adv Monitor app :1.47 disconnected from D-Bus Feb 07 09:19:47 raspberry volumio[1008]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 07 09:19:47 raspberry volumio[1008]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 07 09:19:47 raspberry volumio[1008]: info: Shairport-Sync Started Feb 07 09:19:47 raspberry volumio[1008]: info: Remote SSH Started Feb 07 09:19:47 raspberry volumiobt[3212]: [bluetoothctl]> Feb 07 09:19:47 raspberry volumiobt[3218]: INFO [BTSTART] Registering Bluetooth agent... Feb 07 09:19:47 raspberry volumiobt[3219]: [NEW] Media /org/bluez/hci0 Feb 07 09:19:47 raspberry volumiobt[3219]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry volumiobt[3219]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry volumiobt[3219]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry sudo[3175]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:47 raspberry bluetoothd[738]: Adv Monitor app :1.48 disconnected from D-Bus Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.546 [3113.3113] INFO VolumeManager: [0x374268]: Setting new playback volume: 75 Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.546 [3113.3113] INFO VolumeManager: [0x374268]: Setting new mute state: 0 Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.547 [3113.3113] INFO QobuzConnect: [0x374c38]: Client initialized! Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.549 [3113.3113] INFO SampleApp: Starting Avahi advertising, name: Raspberry, service name: _qobuz-connect._tcp Feb 07 09:19:47 raspberry volumiossh-tunnel[3196]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 07 09:19:47 raspberry volumiobt[3220]: No agent is registered Feb 07 09:19:47 raspberry volumiobt[3220]: [NEW] Media /org/bluez/hci0 Feb 07 09:19:47 raspberry volumiobt[3220]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry volumiobt[3220]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry volumiobt[3220]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.601 [3113.3113] INFO LocalConfigManager: [0x373b48]: Starting Local Configuration server Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.602 [3113.3113] INFO SampleApp: Starting Local configuration server Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.602 [3113.3113] INFO SampleApp: Playback volume changed: 75 Feb 07 09:19:47 raspberry qobuz-connect[3113]: 20260207 09:19:47.602 [3113.3113] INFO SampleApp: Connected to UNIX socket client 0x35e818 Feb 07 09:19:47 raspberry bluetoothd[738]: Adv Monitor app :1.49 disconnected from D-Bus Feb 07 09:19:47 raspberry volumiobt[3223]: INFO [BTSTART] Agent registered successfully. Feb 07 09:19:47 raspberry volumiobt[3224]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Feb 07 09:19:47 raspberry volumio[1008]: info: Preparing to generate the ALSA configuration file Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 09:19:48 raspberry volumio[1008]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 07 09:19:48 raspberry volumio[1008]: info: Received Get System Version Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 09:19:48 raspberry volumio[1008]: info: Received Get System Info Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 09:19:48 raspberry volumio[1008]: info: Discovery: Getting this device information Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:48 raspberry volumio[1008]: info: TidalConnect service stoped! Feb 07 09:19:48 raspberry volumio[1008]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 07 09:19:48 raspberry volumio[1008]: info: Asound.conf file unchanged, so no further update is needed Feb 07 09:19:48 raspberry volumio[1008]: info: Output device has changed, restarting MPD Feb 07 09:19:48 raspberry volumio[1008]: info: Output device has changed, restarting Shairport Sync Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:48 raspberry sudo[3233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 09:19:48 raspberry sudo[3233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry sudo[3231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 09:19:48 raspberry sudo[3231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry sudo[3231]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:48 raspberry systemd[1]: mpd.service: Deactivated successfully. Feb 07 09:19:48 raspberry systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 09:19:48 raspberry systemd[1]: mpd.service: Consumed 1.654s CPU time. Feb 07 09:19:48 raspberry systemd[1]: mpd.socket: Deactivated successfully. Feb 07 09:19:48 raspberry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 09:19:48 raspberry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 09:19:48 raspberry volumio[1008]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 09:19:48 raspberry volumio[1008]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: QobuzConnect: setDeactiveState invoked Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:48 raspberry volumio[1008]: info: Preparing to generate the ALSA configuration file Feb 07 09:19:48 raspberry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 09:19:48 raspberry systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 09:19:48 raspberry volumio[1008]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 07 09:19:48 raspberry volumio[1008]: info: Reading ALSA contributions from plugins. Feb 07 09:19:48 raspberry sudo[3250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 07 09:19:48 raspberry sudo[3250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry volumio[1008]: info: MPD Permissions set Feb 07 09:19:48 raspberry volumio[1008]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 07 09:19:48 raspberry volumio[1008]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 07 09:19:48 raspberry sudo[3250]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry sudo[3260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 07 09:19:48 raspberry sudo[3261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 07 09:19:48 raspberry sudo[3260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:48 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:48 raspberry volumio[1008]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.FNM6XEfFQvYNBKr0APV9FBb19Kn2.fc6f829ab1822e1a2ddf4393437dfea6.state.status' Feb 07 09:19:48 raspberry volumio[1008]: info: Starting Shairport Sync Feb 07 09:19:48 raspberry qobuz-connect[3113]: 20260207 09:19:48.940 [3113.3113] INFO SampleApp: Stopping Local configuration server Feb 07 09:19:48 raspberry systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 07 09:19:48 raspberry sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 09:19:48 raspberry sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:48 raspberry volumio[1008]: info: Asound.conf file written Feb 07 09:19:49 raspberry sudo[3276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 07 09:19:49 raspberry sudo[3276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:49 raspberry systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 07 09:19:49 raspberry sudo[3248]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 09:19:49 raspberry sudo[3248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:19:49 raspberry sudo[3261]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:49 raspberry sudo[3276]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:49 raspberry sudo[3248]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:49 raspberry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 09:19:49 raspberry systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 09:19:49 raspberry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:49 raspberry systemd[1]: shairport-sync.service: Consumed 1.593s CPU time. Feb 07 09:19:49 raspberry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:49 raspberry sudo[3271]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:49 raspberry qobuz-connect[3113]: 20260207 09:19:49.605 [3113.3113] INFO SampleApp: shat down connection on UNIX socket Feb 07 09:19:49 raspberry systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 07 09:19:49 raspberry systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 07 09:19:49 raspberry systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 07 09:19:49 raspberry sudo[3260]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:50 raspberry volumio[1008]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card b1 Feb 07 09:19:50 raspberry volumio[1008]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 07 09:19:50 raspberry volumio[1008]: /usr/share/alsa/init/bcm2835:14: control element not found Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card b1 Feb 07 09:19:50 raspberry volumio[1008]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card Headphones Feb 07 09:19:50 raspberry volumio[1008]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 07 09:19:50 raspberry volumio[1008]: /usr/share/alsa/init/bcm2835:20: control element not found Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card Headphones Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card Device Feb 07 09:19:50 raspberry volumio[1008]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0014" "" "" Feb 07 09:19:50 raspberry volumio[1008]: Hardware is initialized using a generic method Feb 07 09:19:50 raspberry volumio[1008]: No state is present for card Device Feb 07 09:19:50 raspberry volumio[1008]: info: Output device has changed, restarting MPD Feb 07 09:19:50 raspberry volumio[1008]: info: Output device has changed, restarting Shairport Sync Feb 07 09:19:50 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:50 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:50 raspberry sudo[3302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 09:19:50 raspberry sudo[3302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:50 raspberry sudo[3302]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:50 raspberry sudo[3305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 09:19:50 raspberry sudo[3305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:51 raspberry volumio[1008]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 09:19:51 raspberry volumio[1008]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: QobuzConnect: setDeactiveState invoked Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:51 raspberry systemd[1]: mpd.service: Deactivated successfully. Feb 07 09:19:51 raspberry systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 09:19:51 raspberry systemd[1]: mpd.socket: Deactivated successfully. Feb 07 09:19:51 raspberry mpd_monitor.sh[3262]: MPD error: Connection reset by peer Feb 07 09:19:51 raspberry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 09:19:51 raspberry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 09:19:51 raspberry mpd_monitor.sh[2399]: MPD Monitor Service: MPD Appears to be inactive, restarting Feb 07 09:19:51 raspberry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 09:19:51 raspberry volumio[1008]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Feb 07 09:19:51 raspberry mpd_monitor.sh[3314]: mpd: no process found Feb 07 09:19:51 raspberry systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 09:19:51 raspberry systemd[1]: mpd.service: Deactivated successfully. Feb 07 09:19:51 raspberry systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 09:19:51 raspberry volumio[1008]: info: MPD Permissions set Feb 07 09:19:51 raspberry volumio[1008]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 07 09:19:51 raspberry volumio[1008]: info: QobuzConnect: setDeactiveState invoked Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:51 raspberry volumio[1008]: info: Shairport-Sync Started Feb 07 09:19:51 raspberry sudo[3317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 07 09:19:51 raspberry systemd[1]: mpd.socket: Deactivated successfully. Feb 07 09:19:51 raspberry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 09:19:51 raspberry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 09:19:51 raspberry sudo[3317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:51 raspberry volumio[1008]: info: Executing endpoint qc_getconfig Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 07 09:19:51 raspberry sudo[3324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 07 09:19:51 raspberry sudo[3324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:51 raspberry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.380 [3300.3300] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 07 09:19:51 raspberry systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.410 [3300.3300] INFO VolumeManager: [0xfc1268]: Setting new playback volume: 75 Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.410 [3300.3300] INFO VolumeManager: [0xfc1268]: Setting new mute state: 0 Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.410 [3300.3300] INFO QobuzConnect: [0xfc1c38]: Client initialized! Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.410 [3300.3300] INFO SampleApp: Starting Avahi advertising, name: Raspberry, service name: _qobuz-connect._tcp Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.458 [3300.3300] INFO LocalConfigManager: [0xfc0b48]: Starting Local Configuration server Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.458 [3300.3300] INFO SampleApp: Starting Local configuration server Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.459 [3300.3300] INFO SampleApp: Connected to UNIX socket client 0xfab818 Feb 07 09:19:51 raspberry sudo[3324]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 09:19:51 raspberry volumio[1008]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 07 09:19:51 raspberry volumio[1008]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 07 09:19:51 raspberry systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 07 09:19:51 raspberry volumio[1008]: info: Starting Shairport Sync Feb 07 09:19:51 raspberry systemd[1]: vtcs.service: Deactivated successfully. Feb 07 09:19:51 raspberry systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.535 [3300.3300] INFO SampleApp: Playback volume changed: 75 Feb 07 09:19:51 raspberry sudo[3340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 07 09:19:51 raspberry sudo[3340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:51 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:51 raspberry sudo[3317]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:51 raspberry sudo[3342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 09:19:51 raspberry sudo[3342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:51 raspberry qobuz-connect[3300]: 20260207 09:19:51.661 [3300.3300] INFO SampleApp: Stopping Local configuration server Feb 07 09:19:51 raspberry systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 07 09:19:51 raspberry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 09:19:51 raspberry systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 09:19:51 raspberry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:51 raspberry systemd[1]: shairport-sync.service: Consumed 1.631s CPU time. Feb 07 09:19:51 raspberry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:19:51 raspberry sudo[3342]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:51 raspberry volumio[1008]: info: Shairport-Sync Started Feb 07 09:19:51 raspberry sudo[3332]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 09:19:51 raspberry sudo[3332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:19:51 raspberry sudo[3332]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:52 raspberry volumio[1008]: info: TidalConnect service started! Feb 07 09:19:53 raspberry volumiobt[3225]: INFO:a2dp-agent:Connecting to system bus. Feb 07 09:19:53 raspberry volumiobt[3225]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Feb 07 09:19:53 raspberry volumiobt[3225]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Feb 07 09:19:53 raspberry volumiobt[3225]: INFO:a2dp-agent:Registering agent. Feb 07 09:19:53 raspberry volumiobt[3225]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Feb 07 09:19:53 raspberry volumiobt[3225]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Feb 07 09:19:53 raspberry qobuz-connect[3300]: 20260207 09:19:53.467 [3300.3300] INFO SampleApp: shat down connection on UNIX socket Feb 07 09:19:53 raspberry volumio[1008]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 07 09:19:53 raspberry volumio[1008]: info: QobuzConnect: setDeactiveState invoked Feb 07 09:19:53 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:53 raspberry systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 07 09:19:53 raspberry systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 07 09:19:53 raspberry systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 07 09:19:53 raspberry sudo[3340]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:53 raspberry volumio[1008]: info: Executing endpoint qc_getconfig Feb 07 09:19:53 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.768 [3363.3363] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 07 09:19:53 raspberry volumio[1008]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 07 09:19:53 raspberry volumio[1008]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.776 [3363.3363] INFO VolumeManager: [0x18e8268]: Setting new playback volume: 75 Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.776 [3363.3363] INFO VolumeManager: [0x18e8268]: Setting new mute state: 0 Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.776 [3363.3363] INFO QobuzConnect: [0x18e8c38]: Client initialized! Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.776 [3363.3363] INFO SampleApp: Starting Avahi advertising, name: Raspberry, service name: _qobuz-connect._tcp Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.801 [3363.3363] INFO LocalConfigManager: [0x18e7b48]: Starting Local Configuration server Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.801 [3363.3363] INFO SampleApp: Starting Local configuration server Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.802 [3363.3363] INFO SampleApp: Connected to UNIX socket client 0x18d2818 Feb 07 09:19:53 raspberry qobuz-connect[3363]: 20260207 09:19:53.918 [3363.3363] INFO SampleApp: Playback volume changed: 75 Feb 07 09:19:53 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:54 raspberry volumio[1008]: info: TidalConnect service stoped! Feb 07 09:19:54 raspberry volumio[1008]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 07 09:19:54 raspberry volumio[1008]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 07 09:19:54 raspberry sudo[3393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 07 09:19:54 raspberry sudo[3393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:19:54 raspberry systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 07 09:19:54 raspberry sudo[3393]: pam_unix(sudo:session): session closed for user root Feb 07 09:19:55 raspberry volumio[1008]: info: Executing endpoint tc_getconfig Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 07 09:19:55 raspberry vtcs[3396]: STARTING TidalConnect services, version: 1.5.2.56 Feb 07 09:19:55 raspberry vtcs[3396]: [2026-02-07 09:19:55.759] [tisoc] [critical] [DeviceInfo.cpp:52] ioctl error. ret=-1, errno="No such device" Feb 07 09:19:55 raspberry vtcs[3396]: [2026-02-07 09:19:55.760] [tisoc] [critical] [DeviceInfo.cpp:53] [ASSERT] generateDeviceId(0) Feb 07 09:19:55 raspberry vtcs[3396]: STARTED TidalConnect services. Feb 07 09:19:55 raspberry volumio[1008]: info: Executing endpoint tc_connect Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 07 09:19:55 raspberry volumio[1008]: info: Connecting to TidalConnect Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::servicePushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:55 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:55 raspberry volumio[1008]: 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} Feb 07 09:19:55 raspberry volumio[1008]: verbose: CURRENT POSITION 0 Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::syncState stateService stop Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:55 raspberry volumio[1008]: info: No code Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::servicePushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:55 raspberry volumio[1008]: info: CorePlayQueue::getTrack 0 Feb 07 09:19:55 raspberry volumio[1008]: 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} Feb 07 09:19:55 raspberry volumio[1008]: verbose: CURRENT POSITION 0 Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::syncState stateService stop Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:55 raspberry volumio[1008]: info: No code Feb 07 09:19:55 raspberry volumio[1008]: info: CoreStateMachine::pushState Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioPushState Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output update for this device Feb 07 09:19:55 raspberry volumio[1008]: info: MRS: Pushing multiroomSync output Feb 07 09:19:55 raspberry volumio[1008]: info: CoreCommandRouter::volumioGetState Feb 07 09:19:56 raspberry volumio[1008]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.FNM6XEfFQvYNBKr0APV9FBb19Kn2.fc6f829ab1822e1a2ddf4393437dfea6.state.status' Feb 07 09:19:56 raspberry volumio[1008]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.FNM6XEfFQvYNBKr0APV9FBb19Kn2.fc6f829ab1822e1a2ddf4393437dfea6.state.status' Feb 07 09:19:56 raspberry volumio[1008]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.FNM6XEfFQvYNBKr0APV9FBb19Kn2.fc6f829ab1822e1a2ddf4393437dfea6.state.status' Feb 07 09:19:56 raspberry volumio[1008]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.FNM6XEfFQvYNBKr0APV9FBb19Kn2.fc6f829ab1822e1a2ddf4393437dfea6.state.status' Feb 07 09:19:57 raspberry volumio[1008]: info: TidalConnect service started! Feb 07 09:20:02 raspberry mpd[3348]: 2026-02-07T09:20:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 09:20:02 raspberry systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 09:20:02 raspberry sudo[3305]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:02 raspberry sudo[3233]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:02 raspberry mpd_monitor.sh[2399]: MPD Monitor Service: MPD restarted due to no mpc output. Feb 07 09:20:02 raspberry sudo[3059]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:02 raspberry volumio[1008]: error: MPD error: The expression evaluated to a falsy value: Feb 07 09:20:02 raspberry volumio[1008]: assert.ok(self.idling) Feb 07 09:20:02 raspberry volumio[1008]: error: The expression evaluated to a falsy value: Feb 07 09:20:02 raspberry volumio[1008]: assert.ok(self.idling) Feb 07 09:20:02 raspberry volumio[1008]: error: MPD error: The expression evaluated to a falsy value: Feb 07 09:20:02 raspberry volumio[1008]: assert.ok(self.idling) Feb 07 09:20:02 raspberry volumio[1008]: error: The expression evaluated to a falsy value: Feb 07 09:20:02 raspberry volumio[1008]: assert.ok(self.idling) Feb 07 09:20:02 raspberry volumio[1008]: error: updateQueue error: null Feb 07 09:20:05 raspberry sudo[3436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 09:20:05 raspberry sudo[3434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 09:20:05 raspberry sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 09:20:05 raspberry sudo[3434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:05 raspberry sudo[3436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:05 raspberry sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:05 raspberry systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 07 09:20:05 raspberry systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 07 09:20:05 raspberry systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 07 09:20:05 raspberry systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 07 09:20:05 raspberry sudo[3432]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:05 raspberry sudo[3434]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:05 raspberry sudo[3436]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:05 raspberry mpd_monitor.sh[3440]: MPD Monitor Service: Starting MPD Monitor Service Feb 07 09:20:05 raspberry volumio[1008]: info: Successfully started MPD Monitor Feb 07 09:20:05 raspberry volumio[1008]: info: Successfully started MPD Monitor Feb 07 09:20:05 raspberry volumio[1008]: info: Successfully started MPD Monitor Feb 07 09:20:05 raspberry volumio[1008]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Feb 07 09:20:05 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Feb 07 09:20:08 raspberry volumio[1008]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Feb 07 09:20:08 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Feb 07 09:20:08 raspberry sudo[3446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Feb 07 09:20:08 raspberry sudo[3446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:08 raspberry sudo[3446]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:08 raspberry sudo[3448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Feb 07 09:20:08 raspberry sudo[3448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:08 raspberry sudo[3448]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:08 raspberry volumio[1008]: info: Permissions for /etc/network/interfaces set Feb 07 09:20:08 raspberry volumio[1008]: info: Restarting Wireless Network Feb 07 09:20:08 raspberry volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 07 09:20:08 raspberry volumio[1008]: info: Discovery: Restarting Advertising due to device name change Feb 07 09:20:08 raspberry sudo[3451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 07 09:20:08 raspberry sudo[3451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:08 raspberry systemd[1]: Stopping wireless.service - Wireless Services... Feb 07 09:20:08 raspberry systemd[1]: wireless.service: Killing process 2585 (wpa_supplicant) with signal SIGKILL. Feb 07 09:20:08 raspberry systemd[1]: wireless.service: Deactivated successfully. Feb 07 09:20:08 raspberry systemd[1]: Stopped wireless.service - Wireless Services. Feb 07 09:20:08 raspberry systemd[1]: wireless.service: Consumed 3.206s CPU time. Feb 07 09:20:08 raspberry systemd[1]: Starting wireless.service - Wireless Services... Feb 07 09:20:12 raspberry wireless.js[3453]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Feb 07 09:20:12 raspberry wireless.js[3453]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 07 09:20:12 raspberry wireless.js[3453]: WIRELESS.JS: Cleaning previous... Feb 07 09:20:12 raspberry sudo[3472]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 07 09:20:12 raspberry sudo[3472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:12 raspberry avahi-daemon[2708]: Withdrawing address record for 192.168.1.103 on wlan0. Feb 07 09:20:12 raspberry avahi-daemon[2708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.103. Feb 07 09:20:12 raspberry avahi-daemon[2708]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 07 09:20:12 raspberry sudo[3472]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:12 raspberry sudo[3474]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 07 09:20:12 raspberry sudo[3474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:12 raspberry dhcpcd[703]: wlan0: pid 3473 deleted IP address 192.168.1.103/24 Feb 07 09:20:12 raspberry dhcpcd[703]: wlan0: deleting route to 192.168.1.0/24 Feb 07 09:20:12 raspberry systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 09:20:12 raspberry systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 07 09:20:12 raspberry systemd[1]: welcome.service: Deactivated successfully. Feb 07 09:20:12 raspberry systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 07 09:20:12 raspberry systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 07 09:20:13 raspberry systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 07 09:20:13 raspberry volumio[1008]: info: Discovery: Started advertising with name: Raspberry Feb 07 09:20:13 raspberry dhcpcd[703]: wlan0: deleting default route via 192.168.1.254 Feb 07 09:20:13 raspberry welcome[3477]: Resolved ip:[0] Feb 07 09:20:13 raspberry sudo[3474]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:13 raspberry volumio[1008]: compat.c: read() failed: Resource temporarily unavailable Feb 07 09:20:13 raspberry volumio[1008]: info: Discovery: mDNS temporarily unavailable during network transition Feb 07 09:20:13 raspberry systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 07 09:20:13 raspberry systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 09:20:13 raspberry dhcpcd[703]: wlan0: soliciting a DHCP lease Feb 07 09:20:13 raspberry dhcpcd[703]: wlan0: carrier lost Feb 07 09:20:13 raspberry dhcpcd[3495]: ps_bpf_recvmsg: Network is down Feb 07 09:20:13 raspberry wireless.js[3453]: WIRELESS.JS: Stopped aP Feb 07 09:20:13 raspberry sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 09:20:13 raspberry sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:13 raspberry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 07 09:20:13 raspberry sudo[3514]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:13 raspberry sudo[3516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 07 09:20:13 raspberry sudo[3516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:13 raspberry sudo[3516]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:13 raspberry sudo[3524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 09:20:13 raspberry sudo[3524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:13 raspberry sudo[3524]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:13 raspberry sudo[3526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 07 09:20:13 raspberry sudo[3526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:14 raspberry volumio[1008]: info: Volumio Network Manager: Network status updated: 0 Feb 07 09:20:14 raspberry sudo[3526]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:14 raspberry wireless.js[3453]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Feb 07 09:20:14 raspberry sudo[3546]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 09:20:14 raspberry sudo[3546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:14 raspberry sudo[3546]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:14 raspberry sudo[3548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Feb 07 09:20:14 raspberry sudo[3548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:14 raspberry sudo[3548]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:14 raspberry wireless.js[3453]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Feb 07 09:20:14 raspberry wireless.js[3453]: WIRELESS.JS: Start wireless flow Feb 07 09:20:14 raspberry wireless.js[3453]: WIRELESS.JS: Stopped hotspot (if there).. Feb 07 09:20:14 raspberry sudo[3556]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 07 09:20:14 raspberry sudo[3556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:14 raspberry sudo[3556]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:14 raspberry sudo[3558]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 07 09:20:14 raspberry sudo[3558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:15 raspberry sudo[3558]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:15 raspberry wireless.js[3453]: WIRELESS.JS: DHCP IP fallback Feb 07 09:20:15 raspberry wireless.js[3453]: WIRELESS.JS: Start ap Feb 07 09:20:15 raspberry sudo[3564]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 07 09:20:15 raspberry sudo[3564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:15 raspberry dhcpcd[703]: ps_ctl_dispatch: cannot handle another client Feb 07 09:20:15 raspberry dhcpcd[703]: control_free: No such file or directory Feb 07 09:20:15 raspberry sudo[3564]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:15 raspberry wpa_supplicant[3563]: Successfully initialized wpa_supplicant Feb 07 09:20:15 raspberry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 07 09:20:15 raspberry wpa_supplicant[3563]: nl80211: kernel reports: Registration to specific type not supported Feb 07 09:20:16 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:16 raspberry sudo[3571]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:16 raspberry sudo[3571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:16 raspberry sudo[3571]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: Trying to associate with 14:eb:b6:af:30:69 (SSID='AP_DOWN' freq=2457 MHz) Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: Associated with 14:eb:b6:af:30:69 Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: WPA: Key negotiation completed with 14:eb:b6:af:30:69 [PTK=CCMP GTK=CCMP] Feb 07 09:20:16 raspberry wpa_supplicant[3568]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:eb:b6:af:30:69 completed [id=0 id_str=] Feb 07 09:20:16 raspberry dhcpcd[703]: wlan0: carrier acquired Feb 07 09:20:16 raspberry dhcpcd[703]: wlan0: config file changed, expiring leases Feb 07 09:20:16 raspberry dhcpcd[703]: wlan0: IAID 9e:72:2e:a1 Feb 07 09:20:16 raspberry dhcpcd[703]: wlan0: probing address 192.168.1.13/24 Feb 07 09:20:16 raspberry dhcpcd[703]: wlan0: soliciting an IPv6 router Feb 07 09:20:17 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:17 raspberry sudo[3577]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:17 raspberry sudo[3577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:17 raspberry sudo[3577]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:18 raspberry ntpd[855]: IO: Deleting interface #5 wlan0, 192.168.1.103#123, interface stats: received=141, sent=141, dropped=0, active_time=103 secs Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 81.25.28.124 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 80.211.220.41 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 89.221.219.86 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 194.182.77.109 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 85.163.168.227 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 81.27.192.20 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 162.159.200.1 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 194.182.84.241 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 5.1.56.123 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 188.124.59.142 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 46.28.110.153 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 194.182.80.44 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 109.224.72.175 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 78.108.102.237 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 176.118.170.235 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry ntpd[855]: PROTO: 162.159.200.123 unlink local addr 192.168.1.103 -> Feb 07 09:20:18 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:18 raspberry sudo[3581]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:18 raspberry sudo[3581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:18 raspberry sudo[3581]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:19 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:19 raspberry sudo[3585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:19 raspberry sudo[3585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:19 raspberry sudo[3585]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:20 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:20 raspberry sudo[3589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:20 raspberry sudo[3589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:20 raspberry sudo[3589]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:21 raspberry volumio[1008]: info: Discovery: Disconnected from remote: 192.168.1.11 Feb 07 09:20:21 raspberry volumio[1008]: info: MRS: Cleaning up device: 0dcdd5a1-b560-4365-9fe3-6572fd09e754 Feb 07 09:20:21 raspberry volumio[1008]: info: Removing audio output: Feb 07 09:20:21 raspberry avahi-daemon[2708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.13. Feb 07 09:20:21 raspberry dhcpcd[703]: wlan0: using static address 192.168.1.13/24 Feb 07 09:20:21 raspberry dhcpcd[703]: wlan0: adding route to 192.168.1.0/24 Feb 07 09:20:21 raspberry dhcpcd[703]: wlan0: adding default route via 192.168.1.254 Feb 07 09:20:21 raspberry avahi-daemon[2708]: New relevant interface wlan0.IPv4 for mDNS. Feb 07 09:20:21 raspberry avahi-daemon[2708]: Registering new address record for 192.168.1.13 on wlan0.IPv4. Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: trying... Feb 07 09:20:21 raspberry systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 09:20:21 raspberry systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 07 09:20:21 raspberry systemd[1]: welcome.service: Deactivated successfully. Feb 07 09:20:21 raspberry systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 07 09:20:21 raspberry systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 07 09:20:21 raspberry sudo[3605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 07 09:20:21 raspberry sudo[3605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:21 raspberry sudo[3605]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: Connected to: ----AP_DOWN---- Feb 07 09:20:21 raspberry systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 07 09:20:21 raspberry welcome[3611]: Resolved ip:[1] 192.168.1.13 Feb 07 09:20:21 raspberry sudo[3616]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 09:20:21 raspberry sudo[3616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 09:20:21 raspberry sudo[3616]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:21 raspberry systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 07 09:20:21 raspberry systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.1.13, ipV6 is undefined Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: It's done! AP Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: Restarting avahi-daemon... Feb 07 09:20:21 raspberry volumio[1008]: compat.c: read() failed: Resource temporarily unavailable Feb 07 09:20:21 raspberry volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 09:20:21 raspberry volumio[1008]: Error: dns service error: unknown Feb 07 09:20:21 raspberry volumio[1008]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 07 09:20:21 raspberry volumio[1008]: errorCode: -65537 Feb 07 09:20:21 raspberry volumio[1008]: } Feb 07 09:20:21 raspberry volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 09:20:21 raspberry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 09:20:21 raspberry systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 09:20:21 raspberry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:20:21 raspberry systemd[1]: shairport-sync.service: Consumed 1.823s CPU time. Feb 07 09:20:21 raspberry systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 07 09:20:21 raspberry avahi-daemon[2708]: Got SIGTERM, quitting. Feb 07 09:20:21 raspberry vtcs[3396]: [2026-02-07 09:20:21.619] [tisoc] [error] [avahiImpl.cpp:113] avahiClientCallback() AVAHI_CLIENT_S_COLLISION/AVAHI_CLIENT_FAILURE Feb 07 09:20:21 raspberry avahi-daemon[2708]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.13. Feb 07 09:20:21 raspberry avahi-daemon[2708]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 07 09:20:21 raspberry avahi-daemon[2708]: avahi-daemon 0.8 exiting. Feb 07 09:20:21 raspberry systemd[1]: avahi-daemon.service: Deactivated successfully. Feb 07 09:20:21 raspberry systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 07 09:20:21 raspberry systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Feb 07 09:20:21 raspberry systemd[1]: Started wireless.service - Wireless Services. Feb 07 09:20:21 raspberry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 07 09:20:21 raspberry sudo[3451]: pam_unix(sudo:session): session closed for user root Feb 07 09:20:21 raspberry dbus-daemon[614]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.62' (uid=0 pid=2361 comm="/usr/sbin/smbd --foreground --no-process-group") Feb 07 09:20:21 raspberry avahi-daemon[3638]: Process 2708 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Feb 07 09:20:21 raspberry avahi-daemon[3638]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Feb 07 09:20:21 raspberry avahi-daemon[3638]: Successfully dropped root privileges. Feb 07 09:20:21 raspberry avahi-daemon[3638]: avahi-daemon 0.8 starting up. Feb 07 09:20:21 raspberry wireless.js[3453]: WIRELESS.JS: Notified systemd about wireless ready Feb 07 09:20:21 raspberry dbus-daemon[614]: [system] Successfully activated service 'org.freedesktop.Avahi' Feb 07 09:20:21 raspberry systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Successfully called chroot(). Feb 07 09:20:21 raspberry avahi-daemon[3638]: Successfully dropped remaining capabilities. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Loading service file /services/volumio.service. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.13. Feb 07 09:20:21 raspberry avahi-daemon[3638]: New relevant interface wlan0.IPv4 for mDNS. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Feb 07 09:20:21 raspberry avahi-daemon[3638]: New relevant interface lo.IPv4 for mDNS. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Network interface enumeration completed. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Registering new address record for 192.168.1.13 on wlan0.IPv4. Feb 07 09:20:21 raspberry avahi-daemon[3638]: Registering new address record for 127.0.0.1 on lo.IPv4. Feb 07 09:20:22 raspberry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 09:20:22 raspberry sudo[3660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 09:19' Feb 07 09:20:22 raspberry sudo[3660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 09:20:22 raspberry avahi-daemon[3638]: Server startup complete. Host name is raspberry.local. Local service cookie is 545113013. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"