Jan 08 22:29:00 garage volumio[1198]: info: MyVolumio Plan changed: premium Jan 08 22:29:00 garage volumio[1198]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 08 22:29:00 garage volumio[1198]: info: Removing browser output: myVolumio user plan is not superstar Jan 08 22:29:00 garage volumio[1198]: info: Removing audio output: Jan 08 22:29:00 garage volumio[1198]: info: MYVOLUMIO: Adding device Jan 08 22:29:00 garage volumio[1198]: info: MYVOLUMIO: Evaluating Server Jan 08 22:29:01 garage volumio[1198]: info: Remote config written successfully Jan 08 22:29:01 garage volumio[1198]: info: Starting Tunnel 1 Jan 08 22:29:01 garage volumio[1198]: info: Starting Tunnel Connection Checker Jan 08 22:29:01 garage volumio[1198]: info: MYVolumio Device disabled Jan 08 22:29:01 garage volumio[1198]: info: MyVolumio status changed Jan 08 22:29:01 garage volumio[1198]: info: Streaming services startup Jan 08 22:29:01 garage volumio[1198]: info: Starting Streaming Daemon Jan 08 22:29:01 garage sudo[2400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 08 22:29:01 garage sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:01 garage sudo[2400]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:01 garage volumio[1198]: error: Cannot start Volumio Streaming Daemon Jan 08 22:29:01 garage volumio[1198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 08 22:29:01 garage volumio[1198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 08 22:29:01 garage nmbd[2388]: [2026/01/08 22:29:01.728611, 0] ../../source3/nmbd/nmbd.c:901(main) Jan 08 22:29:01 garage nmbd[2388]: nmbd version 4.17.8-Raspbian started. Jan 08 22:29:01 garage nmbd[2388]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 08 22:29:01 garage nmbd[2388]: [2026/01/08 22:29:01.774973, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 08 22:29:01 garage nmbd[2388]: started asyncdns process 2402 Jan 08 22:29:01 garage systemd[1]: Started nmbd.service - Samba NMB Daemon. Jan 08 22:29:01 garage sudo[2344]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:01 garage sudo[2404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Jan 08 22:29:01 garage sudo[2404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 22:29:02 garage systemd[1]: Stopping smbd.service - Samba SMB Daemon... Jan 08 22:29:02 garage volumio[1198]: info: Setting Geolocation for MyVolumio to eu11 Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:02 garage volumio-remote-updater[646]: Test mode disabled Jan 08 22:29:02 garage volumio-remote-updater[646]: Alpha mode disabled Jan 08 22:29:02 garage volumio-remote-updater[646]: Alpha legacy test mode disabled Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 08 22:29:02 garage systemd[1]: smbd.service: Deactivated successfully. Jan 08 22:29:02 garage systemd[1]: Stopped smbd.service - Samba SMB Daemon. Jan 08 22:29:02 garage volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 08 22:29:02 garage systemd[1]: Starting smbd.service - Samba SMB Daemon... Jan 08 22:29:02 garage volumio[1198]: info: MYVolumio Device enabled Jan 08 22:29:02 garage volumio[1198]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 08 22:29:02 garage volumio[1198]: info: MyVolumio status changed Jan 08 22:29:02 garage volumio[1198]: info: Streaming services startup Jan 08 22:29:02 garage volumio[1198]: info: Starting Streaming Daemon Jan 08 22:29:02 garage sudo[2424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 08 22:29:02 garage sudo[2424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:02 garage volumio[1198]: info: Successfully Added MyVolumio device Jan 08 22:29:02 garage volumio[1198]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 08 22:29:02 garage sudo[2424]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:02 garage volumio[1198]: error: Cannot start Volumio Streaming Daemon Jan 08 22:29:02 garage volumio[1198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 08 22:29:02 garage volumio[1198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 08 22:29:02 garage volumio[1198]: info: Setting Geolocation for MyVolumio to eu7 Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:03 garage volumio[1198]: info: Updating MyVolumio device info Jan 08 22:29:03 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:03 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:03 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:03 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 08 22:29:04 garage smbd[2427]: [2026/01/08 22:29:04.529082, 0] ../../source3/smbd/server.c:1741(main) Jan 08 22:29:04 garage smbd[2427]: smbd version 4.17.8-Raspbian started. Jan 08 22:29:04 garage smbd[2427]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 08 22:29:05 garage systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 08 22:29:05 garage sudo[2404]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:06 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 08 22:29:06 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 08 22:29:13 garage sudo[2447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 08 22:29:13 garage sudo[2447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:13 garage sudo[2447]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:14 garage volumio[1198]: info: MRS: MultiRoom plugin initialized Jan 08 22:29:14 garage volumio[1198]: info: MRS: STOPPING SNAPCLIENT Jan 08 22:29:14 garage volumio[1198]: info: MRS: Snap server stop Jan 08 22:29:14 garage volumio[1198]: info: MRS: STOPPING volumioStreaming Jan 08 22:29:14 garage sudo[2464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 08 22:29:14 garage sudo[2464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:14 garage sudo[2469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 08 22:29:14 garage sudo[2469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:14 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 08 22:29:14 garage sudo[2466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 08 22:29:14 garage sudo[2466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:14 garage sudo[2469]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:14 garage sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 08 22:29:14 garage sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:14 garage sudo[2464]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:14 garage sudo[2472]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:14 garage sudo[2466]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 08 22:29:15 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 08 22:29:16 garage volumio[1198]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 08 22:29:16 garage volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 08 22:29:16 garage volumio[1198]: info: Updating MyVolumio device info Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 08 22:29:16 garage volumio[1198]: info: Successfully Added MyVolumio device Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 08 22:29:16 garage volumio[1198]: info: Preparing to save Alsa Options, stopping services first Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:16 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::volumioPause Jan 08 22:29:16 garage volumio[1198]: info: CoreStateMachine::pause Jan 08 22:29:16 garage volumio[1198]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"},"output_device":{"value":1,"label":"HiFiBerry DAC"},"disallowPush":true} Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:16 garage volumio[1198]: info: Enabling I2S DAC: HiFiBerry DAC Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jan 08 22:29:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:16 garage sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 08 22:29:16 garage sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:17 garage sudo[2477]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:17 garage volumio[1198]: info: No Overlays Loaded Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:17 garage sudo[2480]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Jan 08 22:29:17 garage sudo[2480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/#sound-dai-cells Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/compatible Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/status Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jan 08 22:29:17 garage kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jan 08 22:29:17 garage sudo[2480]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:17 garage volumio[1198]: info: Device 2 does not have any Mixer Control Available Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 08 22:29:17 garage volumio[1198]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 08 22:29:17 garage volumio[1198]: info: Disabling external Volume Control Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:17 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 22:29:17 garage volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 08 22:29:17 garage volumio[1198]: info: Received Get System Version Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 22:29:17 garage volumio[1198]: info: Received Get System Info Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:17 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:17 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:17 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jan 08 22:29:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:18 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:18 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:18 garage volumio[1198]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Jan 08 22:29:18 garage volumio[1198]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:18 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::Reload Ui Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jan 08 22:29:18 garage volumio[1198]: info: Wizard terminated Successfully Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:18 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::Reload Ui Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jan 08 22:29:18 garage volumio[1198]: info: Wizard terminated Successfully Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:18 garage volumio[1198]: info: MRS: Removed streaming files Jan 08 22:29:18 garage volumio[1198]: info: MRS: volumioStreaming STOPPED Jan 08 22:29:18 garage volumio[1198]: info: MRS: SNAPSERVER STOPPED Jan 08 22:29:18 garage volumio[1198]: info: MRS: SNAPCLIENT STOPPED Jan 08 22:29:18 garage volumio[1198]: info: SAMBA Restarted Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:18 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::Reload Ui Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jan 08 22:29:18 garage volumio[1198]: info: Wizard terminated Successfully Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:18 garage volumio[1198]: info: Player Reboot required after I2S DAC has been enabled in wizard Jan 08 22:29:18 garage volumio[1198]: info: Jan 08 22:29:18 garage volumio[1198]: ---------------------------- MPD announces state update: mixer Jan 08 22:29:18 garage volumio[1198]: info: ControllerMpd::getState Jan 08 22:29:18 garage volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:29:18 garage volumio[1198]: info: Jan 08 22:29:18 garage volumio[1198]: ---------------------------- MPD announces state update: mixer Jan 08 22:29:18 garage volumio[1198]: info: ControllerMpd::getState Jan 08 22:29:18 garage volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:29:18 garage volumio[1198]: info: sendMpdCommand status took 8 milliseconds Jan 08 22:29:18 garage volumio[1198]: verbose: ControllerMpd::parseState Jan 08 22:29:18 garage volumio[1198]: info: ControllerMpd::pushState Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::servicePushState Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:18 garage volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 08 22:29:18 garage volumio[1198]: verbose: CURRENT POSITION 0 Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:18 garage volumio[1198]: info: No code Jan 08 22:29:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:18 garage volumio[1198]: info: ------------------------------ 191ms Jan 08 22:29:18 garage volumio[1198]: info: I2S Param [object Object] successfully enabled Jan 08 22:29:19 garage volumio[1198]: info: sendMpdCommand status took 452 milliseconds Jan 08 22:29:19 garage volumio[1198]: verbose: ControllerMpd::parseState Jan 08 22:29:19 garage volumio[1198]: info: ControllerMpd::pushState Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::servicePushState Jan 08 22:29:19 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:19 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:19 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:19 garage volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 08 22:29:19 garage volumio[1198]: verbose: CURRENT POSITION 0 Jan 08 22:29:19 garage volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 08 22:29:19 garage volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 08 22:29:19 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:19 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:19 garage volumio[1198]: info: No code Jan 08 22:29:19 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:19 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:19 garage volumio[1198]: info: ------------------------------ 653ms Jan 08 22:29:19 garage volumio[1198]: info: Successfully Updated MyVolumio device Jan 08 22:29:19 garage volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Jan 08 22:29:19 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:29:19 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:19 garage sudo[2521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:29:19 garage sudo[2521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:19 garage systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 08 22:29:19 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:29:19 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:19 garage sudo[2519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:29:19 garage sudo[2519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:19 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:19 garage sudo[2519]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:19 garage volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 08 22:29:19 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 08 22:29:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 08 22:29:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 08 22:29:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 08 22:29:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jan 08 22:29:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 08 22:29:20 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 08 22:29:20 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 08 22:29:20 garage volumio[1198]: info: MRS: Pushing multiroomSync output for this device Jan 08 22:29:20 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:20 garage volumio[1198]: info: Adding audio output: Jan 08 22:29:20 garage volumio[1198]: info: Adding audio output: Jan 08 22:29:20 garage bluetoothd[840]: Path / reserved for Adv Monitor app :1.34 Jan 08 22:29:20 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:20 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:20 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 08 22:29:20 garage volumio[1198]: info: Adding METAVOLUMIO REST API Endpoints Jan 08 22:29:20 garage volumio[1198]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 08 22:29:20 garage volumio[1198]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 08 22:29:20 garage volumio[1198]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 08 22:29:20 garage volumio[1198]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 08 22:29:20 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 08 22:29:20 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 08 22:29:20 garage volumio[1198]: info: Preparing CD Folders Jan 08 22:29:20 garage volumio[1198]: info: Adding CD REST API Endpoints Jan 08 22:29:20 garage volumio[1198]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 08 22:29:20 garage volumio[1198]: info: Starting UDEV Watcher for CD Jan 08 22:29:20 garage bluetoothd[840]: Adv Monitor app :1.34 disconnected from D-Bus Jan 08 22:29:20 garage volumio[1198]: info: Detecting CD presence with UDEV Jan 08 22:29:20 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 08 22:29:23 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:23 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:23 garage systemd[1]: mpd.service: Consumed 7.904s CPU time. Jan 08 22:29:23 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:23 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:23 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:23 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:23 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:24 garage systemd[1]: systemd-timedated.service: Deactivated successfully. Jan 08 22:29:24 garage sudo[2553]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 08 22:29:24 garage sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 08 22:29:24 garage sudo[2553]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:26 garage volumio[1198]: warn: [cd-plugin] cdspeedctl: device or media not ready Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 08 22:29:26 garage volumio[1198]: info: Adding inputs REST Endpoints Jan 08 22:29:26 garage volumio[1198]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 08 22:29:26 garage volumio[1198]: info: Scanning Audio Inputs Jan 08 22:29:26 garage volumio[1198]: info: Checking against Known Cards name Jan 08 22:29:26 garage volumio[1198]: info: Adding Server instance for streaming Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 08 22:29:26 garage volumio[1198]: error: Hi Res Audio Failed Login: Missing Login Data Jan 08 22:29:26 garage volumio[1198]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 08 22:29:26 garage volumio[1198]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 08 22:29:26 garage volumio[1198]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 08 22:29:26 garage volumio[1198]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 08 22:29:26 garage volumio[1198]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 08 22:29:26 garage sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:29:26 garage sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:26 garage sudo[2562]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:26 garage volumio[1198]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 08 22:29:26 garage sudo[2570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:29:26 garage volumio[1198]: info: Adding TIDAL REST API Endpoints Jan 08 22:29:26 garage sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:26 garage volumio[1198]: info: Stopping AccessToken refresher cron for QOBUZ Jan 08 22:29:27 garage sudo[2570]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:27 garage sudo[2572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:29:27 garage sudo[2572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:27 garage systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:27 garage sudo[2572]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:27 garage volumio[1198]: info: AccessToken refresher cron started for QOBUZ Jan 08 22:29:27 garage volumio[1198]: info: Adding QOBUZ REST API Endpoints Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:27 garage volumio[1198]: info: MRS: Getting audio outputs on start Jan 08 22:29:27 garage volumio[1198]: info: MRS: Requesting all other devices output Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 08 22:29:27 garage volumio[1198]: info: CoreStateMachine::getcurrentVolume Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Jan 08 22:29:27 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:27 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:27 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:27 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:27 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:27 garage volumio[1198]: info: Got it Jan 08 22:29:27 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:27 garage volumio[1198]: info: Got it Jan 08 22:29:27 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:27 garage volumio[1198]: info: Got it Jan 08 22:29:27 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:27 garage volumio[1198]: info: Got it Jan 08 22:29:27 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:27 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:27 garage volumio[1198]: info: Got it Jan 08 22:29:27 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:27 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:27 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:28 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:28 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:28 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:28 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:28 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:28 garage volumio[1198]: info: Got it Jan 08 22:29:28 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 08 22:29:28 garage volumio[1198]: info: Getting Alsa Cards List without I2S DAC Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 08 22:29:28 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 08 22:29:29 garage volumio[1198]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jan 08 22:29:29 garage volumio[1198]: info: MPD Permissions set Jan 08 22:29:29 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:29 garage sudo[2591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 08 22:29:29 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:29 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:29 garage sudo[2591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:29 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:29 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:29 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:29 garage volumio[1198]: info: Got it Jan 08 22:29:29 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:29 garage sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 08 22:29:29 garage sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:29 garage 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. Jan 08 22:29:29 garage 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. Jan 08 22:29:29 garage volumio-remote-updater[646]: Test mode disabled Jan 08 22:29:29 garage volumio-remote-updater[646]: Alpha mode disabled Jan 08 22:29:29 garage volumio-remote-updater[646]: Alpha legacy test mode disabled Jan 08 22:29:29 garage sudo[2591]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:29 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:29 garage systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 08 22:29:29 garage sudo[2589]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 08 22:29:29 garage volumio-remote-updater[646]: Test mode disabled Jan 08 22:29:29 garage volumio-remote-updater[646]: Alpha mode disabled Jan 08 22:29:29 garage volumio-remote-updater[646]: Alpha legacy test mode disabled Jan 08 22:29:29 garage volumiobt[2605]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 08 22:29:29 garage volumio[1198]: info: Successfully Updated MyVolumio device Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:29 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:29 garage autossh[2600]: port set to 0, monitoring disabled Jan 08 22:29:29 garage autossh[2600]: starting ssh (count 1) Jan 08 22:29:29 garage autossh[2600]: ssh child pid is 2607 Jan 08 22:29:29 garage sudo[2606]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 08 22:29:29 garage sudo[2606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:29 garage sudo[2606]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:29 garage volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 08 22:29:29 garage volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 08 22:29:29 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 08 22:29:30 garage sudo[2615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 08 22:29:30 garage sudo[2615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:30 garage sudo[2615]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:30 garage volumiobt[2617]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 08 22:29:30 garage volumio[1198]: info: Executing endpoint qc_getconfig Jan 08 22:29:30 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 08 22:29:30 garage qobuz-connect[2574]: 20260108 22:29:30.313 [2574.2574] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 08 22:29:30 garage volumio[1198]: info: TidalConnect service stoped! Jan 08 22:29:30 garage volumio[1198]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 08 22:29:30 garage volumio[1198]: info: CoreCommandRouter::volumioStop Jan 08 22:29:30 garage volumio[1198]: info: CoreStateMachine::stop Jan 08 22:29:30 garage volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 22:29:30 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jan 08 22:29:30 garage volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 22:29:30 garage volumio[1198]: info: Remote SSH Started Jan 08 22:29:30 garage qobuz-connect[2574]: 20260108 22:29:30.671 [2574.2574] INFO VolumeManager: [0x15bf268]: Setting new playback volume: 75 Jan 08 22:29:30 garage qobuz-connect[2574]: 20260108 22:29:30.672 [2574.2574] INFO VolumeManager: [0x15bf268]: Setting new mute state: 0 Jan 08 22:29:30 garage qobuz-connect[2574]: 20260108 22:29:30.672 [2574.2574] INFO QobuzConnect: [0x15bfc38]: Client initialized! Jan 08 22:29:30 garage qobuz-connect[2574]: 20260108 22:29:30.672 [2574.2574] INFO SampleApp: Starting Avahi advertising, name: Garage, service name: _qobuz-connect._tcp Jan 08 22:29:30 garage bluetoothd[840]: Adv Monitor app :1.36 disconnected from D-Bus Jan 08 22:29:30 garage volumiobt[2627]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 08 22:29:31 garage qobuz-connect[2574]: 20260108 22:29:31.002 [2574.2574] INFO LocalConfigManager: [0x15beb48]: Starting Local Configuration server Jan 08 22:29:31 garage qobuz-connect[2574]: 20260108 22:29:31.002 [2574.2574] INFO SampleApp: Starting Local configuration server Jan 08 22:29:31 garage qobuz-connect[2574]: 20260108 22:29:31.002 [2574.2574] INFO SampleApp: Playback volume changed: 75 Jan 08 22:29:31 garage qobuz-connect[2574]: 20260108 22:29:31.003 [2574.2574] INFO SampleApp: Connected to UNIX socket client 0x15a9818 Jan 08 22:29:31 garage volumiossh-tunnel[2607]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 08 22:29:31 garage volumiobt[2628]: [176B blob data] Jan 08 22:29:31 garage volumiobt[2628]: [157B blob data] Jan 08 22:29:31 garage volumiobt[2628]: [157B blob data] Jan 08 22:29:31 garage volumiobt[2628]: [157B blob data] Jan 08 22:29:31 garage volumiobt[2628]: [113B blob data] Jan 08 22:29:31 garage volumiobt[2628]: [bluetoothctl]> discoverable on Jan 08 22:29:31 garage volumiobt[2628]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 08 22:29:31 garage volumiobt[2628]: [bluetoothctl]> pairable on Jan 08 22:29:31 garage bluetoothd[840]: Path / reserved for Adv Monitor app :1.38 Jan 08 22:29:31 garage bluetoothd[840]: Adv Monitor app :1.38 disconnected from D-Bus Jan 08 22:29:31 garage volumiobt[2628]: [bluetoothctl]> Jan 08 22:29:31 garage volumiobt[2631]: INFO [BTSTART] Registering Bluetooth agent... Jan 08 22:29:31 garage volumiobt[2632]: [NEW] Media /org/bluez/hci0 Jan 08 22:29:31 garage volumiobt[2632]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage volumiobt[2632]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage volumiobt[2632]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage bluetoothd[840]: Adv Monitor app :1.39 disconnected from D-Bus Jan 08 22:29:31 garage volumiobt[2633]: No agent is registered Jan 08 22:29:31 garage volumiobt[2633]: [NEW] Media /org/bluez/hci0 Jan 08 22:29:31 garage volumiobt[2633]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage volumiobt[2633]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage volumiobt[2633]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 08 22:29:31 garage bluetoothd[840]: Adv Monitor app :1.40 disconnected from D-Bus Jan 08 22:29:31 garage volumiobt[2634]: INFO [BTSTART] Agent registered successfully. Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:31 garage volumiobt[2636]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:32 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:32 garage volumio[1198]: info: Got it Jan 08 22:29:32 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:32 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:32 garage volumio[1198]: info: Got it Jan 08 22:29:32 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:32 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:32 garage volumio[1198]: info: Got it Jan 08 22:29:32 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:32 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:32 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:32 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:32 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:33 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:33 garage volumio[1198]: info: Got it Jan 08 22:29:33 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:33 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:33 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:33 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:33 garage volumio[1198]: info: Got it Jan 08 22:29:33 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:33 garage volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 08 22:29:33 garage volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:33 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:33 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:29:34 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: info: Got it Jan 08 22:29:35 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:35 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:35 garage volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 08 22:29:35 garage volumio[1198]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:35 garage volumio[1198]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:35 garage volumio[1198]: info: Starting Shairport Sync Jan 08 22:29:35 garage volumio[1198]: info: Asound.conf file written Jan 08 22:29:35 garage sudo[2677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 08 22:29:35 garage sudo[2673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 08 22:29:35 garage sudo[2673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:35 garage sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:36 garage sudo[2677]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:36 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:29:36 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:29:36 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:36 garage systemd[1]: shairport-sync.service: Consumed 1.022s CPU time. Jan 08 22:29:36 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:36 garage sudo[2673]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: No state is present for card b1 Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: /usr/share/alsa/init/bcm2835:14: control element not found Jan 08 22:29:36 garage volumio[1198]: No state is present for card b1 Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: No state is present for card Headphones Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: /usr/share/alsa/init/bcm2835:20: control element not found Jan 08 22:29:36 garage volumio[1198]: No state is present for card Headphones Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:29:36 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:29:36 garage volumio[1198]: Found hardware: "RPi-simple" "" "" "" "" Jan 08 22:29:36 garage volumio[1198]: Hardware is initialized using a generic method Jan 08 22:29:36 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:29:36 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:29:36 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:36 garage sudo[2697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:29:36 garage sudo[2697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:36 garage sudo[2700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:29:36 garage sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:36 garage sudo[2697]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:36 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:29:36 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:36 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:36 garage volumio[1198]: 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)"} Jan 08 22:29:36 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:29:36 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:36 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:36 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:36 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:36 garage systemd[1]: mpd.service: Consumed 2.872s CPU time. Jan 08 22:29:36 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:36 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:36 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:36 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:36 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:37 garage sudo[2712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:29:37 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:29:37 garage sudo[2712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:37 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:37 garage sudo[2712]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:37 garage sudo[2715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:29:37 garage sudo[2715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:37 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:37 garage volumio[1198]: 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:872:41)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Jan 08 22:29:37 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:37 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:37 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:37 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:37 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:37 garage sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:29:37 garage sudo[2725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:37 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:37 garage sudo[2729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:29:37 garage sudo[2729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage sudo[2735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:29:37 garage sudo[2735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:37 garage volumio[1198]: info: Got it Jan 08 22:29:37 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:37 garage sudo[2725]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:37 garage sudo[2744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:29:37 garage sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage sudo[2729]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:37 garage sudo[2735]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:37 garage sudo[2748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:29:37 garage sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:37 garage sudo[2744]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:37 garage systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 08 22:29:37 garage qobuz-connect[2574]: 20260108 22:29:37.993 [2574.2574] INFO SampleApp: Stopping Local configuration server Jan 08 22:29:38 garage sudo[2750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:29:38 garage sudo[2750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:38 garage volumiobt[2637]: INFO:a2dp-agent:Connecting to system bus. Jan 08 22:29:38 garage volumiobt[2637]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Jan 08 22:29:38 garage volumiobt[2637]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Jan 08 22:29:38 garage volumiobt[2637]: INFO:a2dp-agent:Registering agent. Jan 08 22:29:38 garage volumiobt[2637]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Jan 08 22:29:38 garage volumiobt[2637]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Jan 08 22:29:38 garage sudo[2738]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 08 22:29:38 garage sudo[2738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 08 22:29:39 garage sudo[2738]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:39 garage qobuz-connect[2574]: 20260108 22:29:39.025 [2574.2574] INFO SampleApp: shat down connection on UNIX socket Jan 08 22:29:39 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:39 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:39 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:39 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:39 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:39 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:39 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:39 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:39 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:39 garage volumio[1198]: info: Got it Jan 08 22:29:39 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:39 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:29:39 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:40 garage systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 08 22:29:40 garage systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:41 garage systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:41 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage sudo[2750]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:41 garage sudo[2748]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:41 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:29:41 garage volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 08 22:29:41 garage volumio[1198]: info: MPD Permissions set Jan 08 22:29:41 garage volumio[1198]: info: MPD Permissions set Jan 08 22:29:41 garage volumio[1198]: info: Shairport-Sync Started Jan 08 22:29:41 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: Got it Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:41 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:41 garage volumio[1198]: info: TidalConnect service stoped! Jan 08 22:29:41 garage volumio[1198]: info: TidalConnect service stoped! Jan 08 22:29:42 garage volumio[1198]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 08 22:29:42 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:42 garage sudo[2771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 08 22:29:42 garage sudo[2771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:42 garage systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 08 22:29:42 garage sudo[2771]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:42 garage volumio[1198]: info: Got it Jan 08 22:29:42 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:43 garage volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Jan 08 22:29:43 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:29:43 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:29:43 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:43 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:43 garage sudo[2810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:29:43 garage sudo[2810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:43 garage sudo[2810]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:43 garage sudo[2813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:29:43 garage sudo[2813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:43 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:29:43 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:43 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:43 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:43 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:43 garage volumio[1198]: 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)"} Jan 08 22:29:43 garage volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 08 22:29:43 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:43 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:43 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:43 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:43 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:43 garage mpd_monitor.sh[2759]: MPD error: Connection reset by peer Jan 08 22:29:43 garage mpd_monitor.sh[1966]: MPD Monitor Service: MPD Appears to be inactive, restarting Jan 08 22:29:43 garage mpd_monitor.sh[2823]: mpd: no process found Jan 08 22:29:43 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:43 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:43 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:43 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:43 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:43 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:43 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:43 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:43 garage volumiossh-tunnel[2607]: Connection to eu7.myvolumio.org closed by remote host. Jan 08 22:29:43 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:44 garage autossh[2600]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:44 garage sudo[2829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:29:44 garage sudo[2829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:44 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:29:44 garage volumio[1198]: info: Got it Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:44 garage volumio[1198]: info: Got it Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:29:44 garage volumio[1198]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 08 22:29:44 garage volumio[1198]: info: Reading ALSA contributions from plugins. Jan 08 22:29:44 garage volumio[1198]: info: Executing endpoint qc_getconfig Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 08 22:29:44 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.271 [2758.2758] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 08 22:29:44 garage volumio[1198]: info: MPD Permissions set Jan 08 22:29:44 garage volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:44 garage volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:44 garage volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:44 garage volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:44 garage volumio[1198]: info: Starting Shairport Sync Jan 08 22:29:44 garage volumio[1198]: info: Starting Shairport Sync Jan 08 22:29:44 garage sudo[2836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:29:44 garage sudo[2836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.321 [2758.2758] INFO VolumeManager: [0x1133268]: Setting new playback volume: 75 Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.321 [2758.2758] INFO VolumeManager: [0x1133268]: Setting new mute state: 0 Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.321 [2758.2758] INFO QobuzConnect: [0x1133c38]: Client initialized! Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.321 [2758.2758] INFO SampleApp: Starting Avahi advertising, name: Garage, service name: _qobuz-connect._tcp Jan 08 22:29:44 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:44 garage volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 08 22:29:44 garage systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 08 22:29:44 garage volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 08 22:29:44 garage sudo[2843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 08 22:29:44 garage sudo[2843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage autossh[2845]: port set to 0, monitoring disabled Jan 08 22:29:44 garage autossh[2845]: starting ssh (count 1) Jan 08 22:29:44 garage autossh[2845]: ssh child pid is 2850 Jan 08 22:29:44 garage systemd[1]: vtcs.service: Deactivated successfully. Jan 08 22:29:44 garage systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 08 22:29:44 garage sudo[2846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 08 22:29:44 garage sudo[2846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 08 22:29:44 garage sudo[2836]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:44 garage sudo[2829]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:44 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:29:44 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:29:44 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:44 garage sudo[2843]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:44 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:29:44 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:29:44 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:44 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:44 garage sudo[2846]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:44 garage sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:29:44 garage sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage volumiossh-tunnel[2850]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 08 22:29:44 garage systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 08 22:29:44 garage autossh[2845]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:44 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:44 garage sudo[2863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 08 22:29:44 garage sudo[2863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:44 garage volumio[1198]: info: Shairport-Sync Started Jan 08 22:29:44 garage volumio[1198]: info: Shairport-Sync Started Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.968 [2758.2758] INFO LocalConfigManager: [0x1132b48]: Starting Local Configuration server Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.968 [2758.2758] INFO SampleApp: Starting Local configuration server Jan 08 22:29:44 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 08 22:29:44 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:44 garage qobuz-connect[2758]: 20260108 22:29:44.986 [2758.2758] INFO SampleApp: Stopping Local configuration server Jan 08 22:29:45 garage sudo[2865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 08 22:29:45 garage sudo[2865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:45 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:45 garage autossh[2884]: port set to 0, monitoring disabled Jan 08 22:29:45 garage volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.87c2746a9f5b9c8f837c0702eb90cb9d.state.status' Jan 08 22:29:45 garage autossh[2884]: starting ssh (count 1) Jan 08 22:29:45 garage autossh[2884]: ssh child pid is 2887 Jan 08 22:29:45 garage volumio[1198]: info: Starting Shairport Sync Jan 08 22:29:45 garage systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 08 22:29:45 garage sudo[2863]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:45 garage sudo[2865]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:45 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:45 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:45 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:45 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:45 garage sudo[2893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 08 22:29:45 garage sudo[2893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:45 garage volumiossh-tunnel[2887]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 22:29:45 garage autossh[2884]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:45 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:45 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:45 garage volumio[1198]: info: Asound.conf file written Jan 08 22:29:45 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 08 22:29:45 garage sudo[2898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 08 22:29:45 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:45 garage sudo[2898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:45 garage sudo[2898]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:45 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:45 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:29:45 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:29:45 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:45 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:45 garage sudo[2893]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:45 garage autossh[2905]: port set to 0, monitoring disabled Jan 08 22:29:45 garage autossh[2905]: starting ssh (count 1) Jan 08 22:29:45 garage autossh[2905]: ssh child pid is 2908 Jan 08 22:29:45 garage sudo[2827]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 08 22:29:45 garage sudo[2827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 08 22:29:45 garage sudo[2827]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: No state is present for card b1 Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: /usr/share/alsa/init/bcm2835:14: control element not found Jan 08 22:29:45 garage volumio[1198]: No state is present for card b1 Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: No state is present for card Headphones Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: /usr/share/alsa/init/bcm2835:20: control element not found Jan 08 22:29:45 garage volumio[1198]: No state is present for card Headphones Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:29:45 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:29:45 garage volumio[1198]: Found hardware: "RPi-simple" "" "" "" "" Jan 08 22:29:45 garage volumio[1198]: Hardware is initialized using a generic method Jan 08 22:29:45 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:29:45 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:29:45 garage volumiossh-tunnel[2908]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 08 22:29:45 garage autossh[2905]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:45 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:45 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:45 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:45 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:46 garage sudo[2921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:29:46 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 08 22:29:46 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:46 garage sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:29:46 garage sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:46 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:29:46 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:29:46 garage sudo[2921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:46 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:46 garage sudo[2921]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:46 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:46 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:46 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:46 garage autossh[2937]: port set to 0, monitoring disabled Jan 08 22:29:46 garage volumio[1198]: 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)"} Jan 08 22:29:46 garage autossh[2937]: starting ssh (count 1) Jan 08 22:29:46 garage autossh[2937]: ssh child pid is 2941 Jan 08 22:29:46 garage sudo[2943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:29:46 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:29:46 garage sudo[2943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:46 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:29:46 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:29:46 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:29:46 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:29:46 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:29:46 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:29:46 garage volumiossh-tunnel[2941]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 08 22:29:46 garage autossh[2937]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:46 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:46 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:46 garage volumio[1198]: info: TidalConnect service started! Jan 08 22:29:46 garage systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 08 22:29:46 garage systemd[1]: vtcs.service: Deactivated successfully. Jan 08 22:29:46 garage systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 08 22:29:46 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 08 22:29:46 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 08 22:29:46 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:46 garage sudo[2952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:29:46 garage sudo[2943]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:46 garage sudo[2952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:46 garage volumio[1198]: info: MPD Permissions set Jan 08 22:29:46 garage volumio[1198]: info: Shairport-Sync Started Jan 08 22:29:46 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 08 22:29:46 garage systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:46 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 08 22:29:46 garage autossh[2956]: port set to 0, monitoring disabled Jan 08 22:29:46 garage autossh[2956]: starting ssh (count 1) Jan 08 22:29:46 garage autossh[2956]: ssh child pid is 2959 Jan 08 22:29:46 garage sudo[2952]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:46 garage sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 08 22:29:46 garage sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:46 garage volumiossh-tunnel[2959]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 08 22:29:46 garage autossh[2956]: ssh exited prematurely with status 255; autossh exiting Jan 08 22:29:46 garage qobuz-connect[2758]: 20260108 22:29:46.990 [2758.2758] INFO SampleApp: shat down connection on UNIX socket Jan 08 22:29:46 garage systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Jan 08 22:29:46 garage systemd[1]: qobuz-connect.service: Failed with result 'signal'. Jan 08 22:29:46 garage systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:47 garage systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 08 22:29:47 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:47 garage sudo[2962]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage sudo[2961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:29:47 garage sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:47 garage sudo[2853]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage sudo[2967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:47 garage sudo[2967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage sudo[2981]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:47 garage sudo[2967]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage sudo[2981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage sudo[2981]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage sudo[2975]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:47 garage systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 6. Jan 08 22:29:47 garage systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:47 garage systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 08 22:29:47 garage systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 08 22:29:47 garage systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 08 22:29:47 garage sudo[2975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 08 22:29:47 garage sudo[2975]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 08 22:29:47 garage systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:29:47 garage volumio[1198]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 08 22:29:47 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:47 garage sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:29:47 garage sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage volumio[1198]: info: Starting Shairport Sync Jan 08 22:29:47 garage systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:29:47 garage sudo[2992]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:29:47 garage sudo[2961]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage sudo[2992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage sudo[2987]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 08 22:29:47 garage sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:47 garage sudo[2992]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:47 garage volumio[1198]: info: TidalConnect service stoped! Jan 08 22:29:47 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:29:47 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:29:47 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:47 garage sudo[2948]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 08 22:29:47 garage sudo[2948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 08 22:29:47 garage sudo[2948]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:29:47 garage sudo[3001]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:47 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:47 garage volumio[1198]: info: Shairport-Sync Started Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:47 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:47 garage volumio[1198]: info: Executing endpoint qc_getconfig Jan 08 22:29:47 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.152 [2998.2998] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.186 [2998.2998] INFO VolumeManager: [0x53b268]: Setting new playback volume: 75 Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.186 [2998.2998] INFO VolumeManager: [0x53b268]: Setting new mute state: 0 Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.186 [2998.2998] INFO QobuzConnect: [0x53bc38]: Client initialized! Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.186 [2998.2998] INFO SampleApp: Starting Avahi advertising, name: Garage, service name: _qobuz-connect._tcp Jan 08 22:29:48 garage volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 08 22:29:48 garage volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 08 22:29:48 garage volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:48 garage volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:48 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:48 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 08 22:29:48 garage volumio[1198]: info: TidalConnect service started! Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.319 [2998.2998] INFO LocalConfigManager: [0x53ab48]: Starting Local Configuration server Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.319 [2998.2998] INFO SampleApp: Starting Local configuration server Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.319 [2998.2998] INFO SampleApp: Connected to UNIX socket client 0x525818 Jan 08 22:29:48 garage qobuz-connect[2998]: 20260108 22:29:48.320 [2998.2998] INFO SampleApp: Playback volume changed: 75 Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 22:29:48 garage volumio[1198]: info: TidalConnect service started! Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:48 garage sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 08 22:29:48 garage sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:48 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 08 22:29:48 garage systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 08 22:29:48 garage sudo[3035]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:48 garage sudo[3038]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 08 22:29:48 garage sudo[3038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:48 garage sudo[3051]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:48 garage sudo[3051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:48 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 08 22:29:48 garage sudo[3051]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:48 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:48 garage sudo[3038]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:48 garage sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:48 garage sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:48 garage sudo[3061]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:29:48 garage sudo[3061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:48 garage sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:29:48 garage sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:49 garage sudo[3044]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:49 garage sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:29:49 garage sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:49 garage sudo[3057]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:49 garage sudo[3061]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:49 garage sudo[3065]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:49 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:49 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:49 garage volumio[1198]: info: TidalConnect service stoped! Jan 08 22:29:50 garage volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:50 garage volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 08 22:29:50 garage sudo[3081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 08 22:29:50 garage sudo[3081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:50 garage sudo[3085]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:29:50 garage sudo[3085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:50 garage sudo[3084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:29:50 garage sudo[3085]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:50 garage sudo[3084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:50 garage sudo[3084]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:50 garage sudo[3081]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:50 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 08 22:29:50 garage volumio[1198]: info: Executing endpoint tc_getconfig Jan 08 22:29:50 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 08 22:29:50 garage vtcs[3054]: STARTING TidalConnect services, version: 1.5.2.56 Jan 08 22:29:50 garage vtcs[3054]: STARTED TidalConnect services. Jan 08 22:29:50 garage volumio[1198]: info: Executing endpoint tc_connect Jan 08 22:29:50 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 08 22:29:50 garage volumio[1198]: info: Connecting to TidalConnect Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::servicePushState Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:51 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:51 garage volumio[1198]: 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} Jan 08 22:29:51 garage volumio[1198]: verbose: CURRENT POSITION 0 Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:51 garage volumio[1198]: info: No code Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::servicePushState Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:51 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:29:51 garage volumio[1198]: 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} Jan 08 22:29:51 garage volumio[1198]: verbose: CURRENT POSITION 0 Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::syncState stateService stop Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:51 garage volumio[1198]: info: No code Jan 08 22:29:51 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:29:51 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:29:51 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:52 garage volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.87c2746a9f5b9c8f837c0702eb90cb9d.state.status' Jan 08 22:29:52 garage volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.87c2746a9f5b9c8f837c0702eb90cb9d.state.status' Jan 08 22:29:52 garage volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.87c2746a9f5b9c8f837c0702eb90cb9d.state.status' Jan 08 22:29:52 garage volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jEtGjFzCERMejLI477Q2oWfq0fs2.87c2746a9f5b9c8f837c0702eb90cb9d.state.status' Jan 08 22:29:52 garage volumio[1198]: info: TidalConnect service started! Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:52 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:52 garage sudo[3106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:29:52 garage sudo[3106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:52 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 08 22:29:52 garage sudo[3106]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:29:52 garage sudo[3108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:29:52 garage sudo[3108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:29:52 garage sudo[3108]: pam_unix(sudo:session): session closed for user root Jan 08 22:29:52 garage volumio[1198]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 08 22:29:52 garage volumio[1198]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 08 22:29:52 garage volumio[1198]: info: Received Get System Info Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:52 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:52 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:53 garage volumio[1198]: info: Listing playlists Jan 08 22:29:53 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 22:29:53 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 22:29:53 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 08 22:29:53 garage volumio[1198]: info: TidalConnect service started! Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 22:29:54 garage volumio[1198]: info: Received Get System Info Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:54 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:54 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 08 22:29:55 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 22:29:55 garage volumio[1198]: info: Received Get System Info Jan 08 22:29:55 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:29:55 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:29:55 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:29:55 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:29:55 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:29:56 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jan 08 22:30:02 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 08 22:30:03 garage volumio[1198]: info: CoreCommandRouter::Close All Modals sent Jan 08 22:30:04 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:05 garage volumio[1198]: info: Retrieving Cloud Streaming UI Jan 08 22:30:05 garage volumio[1198]: info: Getting Tidal Cloud Configuration Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:05 garage volumio[1198]: info: Getting Qobuz Cloud Configuration Jan 08 22:30:05 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:05 garage volumio[1198]: info: Getting Spotify Cloud Configuration Jan 08 22:30:05 garage volumio[1198]: info: Asking plugin for UI Config Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:05 garage volumio[1198]: info: Saving Spotify Acccount Jan 08 22:30:05 garage volumio[1198]: info: Got it Jan 08 22:30:05 garage volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 08 22:30:05 garage volumio[1198]: info: Got Tidal Cloud Configuration Jan 08 22:30:05 garage volumio[1198]: info: Got it Jan 08 22:30:05 garage volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::volumioGetBrowseSources Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::volumioGetBrowseSources Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::volumioGetBrowseSources Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 08 22:30:06 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 08 22:30:09 garage mpd[3010]: 2026-01-08T22:30:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 08 22:30:09 garage systemd[1]: Started mpd.service - Music Player Daemon. Jan 08 22:30:09 garage sudo[2926]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:09 garage mpd_monitor.sh[1966]: MPD Monitor Service: MPD restarted due to no mpc output. Jan 08 22:30:09 garage sudo[2521]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:09 garage sudo[2700]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:09 garage sudo[2813]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:09 garage sudo[2715]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:10 garage volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: MPD error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: error: The expression evaluated to a falsy value: Jan 08 22:30:10 garage volumio[1198]: assert.ok(self.idling) Jan 08 22:30:10 garage volumio[1198]: info: Disabling MyMusic plugin airplay_emulation Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 22:30:10 garage volumio[1198]: info: Disabling plugin airplay_emulation Jan 08 22:30:10 garage volumio[1198]: info: Done. Jan 08 22:30:10 garage sudo[3153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jan 08 22:30:10 garage sudo[3153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:10 garage volumio[1198]: error: updateQueue error: null Jan 08 22:30:10 garage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 08 22:30:10 garage systemd[1]: shairport-sync.service: Deactivated successfully. Jan 08 22:30:10 garage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 08 22:30:10 garage sudo[3153]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 08 22:30:10 garage volumio[1198]: info: Shairport-Sync Stopped Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 22:30:10 garage volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 08 22:30:10 garage volumio[1198]: info: Received Get System Version Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 22:30:10 garage volumio[1198]: info: Received Get System Info Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:30:10 garage volumio[1198]: info: Discovery: Getting this device information Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:30:10 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:30:11 garage volumio[1198]: info: Disabling MyMusic plugin upnp_browser Jan 08 22:30:11 garage volumio[1198]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMediálne servery Jan 08 22:30:11 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 22:30:11 garage volumio[1198]: info: Disabling plugin upnp_browser Jan 08 22:30:11 garage volumio[1198]: info: Done. Jan 08 22:30:12 garage sudo[3156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 08 22:30:12 garage sudo[3156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:12 garage sudo[3158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 08 22:30:12 garage volumio[1198]: info: Disabling MyMusic plugin bluetooth Jan 08 22:30:12 garage sudo[3158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:12 garage volumio[1198]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Jan 08 22:30:12 garage sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 08 22:30:12 garage sudo[3162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 08 22:30:12 garage sudo[3162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:12 garage sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:12 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] onStop Jan 08 22:30:12 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 08 22:30:12 garage volumio[1198]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 08 22:30:12 garage volumio[1198]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Jan 08 22:30:12 garage systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 08 22:30:12 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Jan 08 22:30:12 garage systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 08 22:30:12 garage systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage sudo[3156]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:13 garage systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 08 22:30:13 garage systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 08 22:30:13 garage systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage sudo[3162]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:13 garage sudo[3161]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:13 garage volumio[1198]: info: Successfully started MPD Monitor Jan 08 22:30:13 garage volumio[1198]: info: Successfully started MPD Monitor Jan 08 22:30:13 garage sudo[3158]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:13 garage mpd_monitor.sh[3171]: MPD Monitor Service: Starting MPD Monitor Service Jan 08 22:30:13 garage sudo[3169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Jan 08 22:30:13 garage sudo[3169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:13 garage volumio[1198]: info: Successfully started MPD Monitor Jan 08 22:30:13 garage volumio[1198]: info: Successfully started MPD Monitor Jan 08 22:30:13 garage sudo[3173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 08 22:30:13 garage sudo[3173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:13 garage systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Jan 08 22:30:13 garage volumiobt[3179]: INFO [BTSTART] Disconnecting all Bluetooth devices... Jan 08 22:30:13 garage systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 08 22:30:13 garage systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 08 22:30:13 garage systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 08 22:30:13 garage sudo[3173]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:13 garage volumio[1198]: info: Successfully started MPD Monitor Jan 08 22:30:13 garage mpd_monitor.sh[3184]: MPD Monitor Service: Starting MPD Monitor Service Jan 08 22:30:13 garage bluetoothd[840]: Adv Monitor app :1.54 disconnected from D-Bus Jan 08 22:30:13 garage volumiobt[3196]: INFO [BTSTART] Unregistering Bluetooth agent... Jan 08 22:30:13 garage volumiobt[3199]: No agent is registered Jan 08 22:30:13 garage volumiobt[3199]: [NEW] Media /org/bluez/hci0 Jan 08 22:30:13 garage volumiobt[3199]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 08 22:30:13 garage volumiobt[3199]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 08 22:30:13 garage volumiobt[3199]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 08 22:30:14 garage bluetoothd[840]: Adv Monitor app :1.55 disconnected from D-Bus Jan 08 22:30:14 garage volumiobt[3204]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Jan 08 22:30:14 garage volumiobt[3206]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Jan 08 22:30:14 garage volumio[1198]: info: Disabling MyMusic plugin multiroom Jan 08 22:30:14 garage volumio[1198]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Jan 08 22:30:14 garage volumio[1198]: info: Disabling plugin multiroom Jan 08 22:30:14 garage volumio[1198]: info: Preparing to generate the ALSA configuration file Jan 08 22:30:16 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 08 22:30:16 garage volumio[1198]: info: Disabling MyMusic plugin metavolumio Jan 08 22:30:16 garage volumio[1198]: info: [MyVolumio PluginManager] Disabling and stopping plugin miscellanea metavolumio Jan 08 22:30:16 garage volumio[1198]: info: Removing METAVOLUMIO REST API Endpoints Jan 08 22:30:16 garage volumio[1198]: info: Removing metavolumio REST Endpoint Jan 08 22:30:16 garage volumio[1198]: info: Removing getSimilarArtists REST Endpoint Jan 08 22:30:16 garage volumio[1198]: info: Removing getSimilarAlbums REST Endpoint Jan 08 22:30:16 garage volumio[1198]: info: Removing getSimilarTracks REST Endpoint Jan 08 22:30:16 garage volumio[1198]: info: Disabling plugin metavolumio Jan 08 22:30:16 garage systemd[1]: volumiobt.service: Deactivated successfully. Jan 08 22:30:16 garage systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Jan 08 22:30:16 garage systemd[1]: volumiobt.service: Consumed 2.001s CPU time. Jan 08 22:30:16 garage sudo[3169]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:16 garage volumio[1198]: info: Asound.conf file written Jan 08 22:30:17 garage sudo[3210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 08 22:30:17 garage sudo[3210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:17 garage sudo[3210]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: No state is present for card b1 Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: /usr/share/alsa/init/bcm2835:14: control element not found Jan 08 22:30:17 garage volumio[1198]: No state is present for card b1 Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: No state is present for card Headphones Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: /usr/share/alsa/init/bcm2835:20: control element not found Jan 08 22:30:17 garage volumio[1198]: No state is present for card Headphones Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:30:17 garage volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 08 22:30:17 garage volumio[1198]: Found hardware: "RPi-simple" "" "" "" "" Jan 08 22:30:17 garage volumio[1198]: Hardware is initialized using a generic method Jan 08 22:30:17 garage volumio[1198]: No state is present for card sndrpihifiberry Jan 08 22:30:17 garage volumio[1198]: info: Output device has changed, restarting MPD Jan 08 22:30:17 garage volumio[1198]: info: Output device has changed, restarting Shairport Sync Jan 08 22:30:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:30:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:30:17 garage sudo[3216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 08 22:30:17 garage sudo[3216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:17 garage sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 08 22:30:17 garage sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:17 garage sudo[3216]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:17 garage volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 08 22:30:17 garage volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 08 22:30:17 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:30:17 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:30:17 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:30:17 garage vtcs[3054]: [2026-01-08 22:30:17.678] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 08 22:30:17 garage vtcs[3054]: [2026-01-08 22:30:17.692] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 08 22:30:17 garage systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 08 22:30:17 garage sudo[3228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:30:17 garage sudo[3228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:17 garage sudo[3230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 08 22:30:18 garage systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 08 22:30:18 garage systemd[1]: vtcs.service: Deactivated successfully. Jan 08 22:30:18 garage systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 08 22:30:18 garage sudo[3230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:18 garage sudo[3237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 08 22:30:18 garage sudo[3237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:18 garage sudo[3228]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Jan 08 22:30:18 garage sudo[3237]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Jan 08 22:30:18 garage volumio[1198]: verbose: UNSET VOLATILE: Service: undefined Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jan 08 22:30:18 garage sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 08 22:30:18 garage sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::resetVolumioState Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::getcurrentVolume Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::resetVolumioState Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::getcurrentVolume Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:30:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:30:18 garage sudo[3230]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:18 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:30:18 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:30:18 garage systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 08 22:30:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:30:18 garage volumio[1198]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioStop Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::stop Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 22:30:18 garage qobuz-connect[2998]: 20260108 22:30:18.415 [2998.2998] INFO SampleApp: Stopping Local configuration server Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::pushState Jan 08 22:30:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioPushState Jan 08 22:30:18 garage volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Jan 08 22:30:18 garage volumio[1198]: info: MRS: Pushing multiroomSync output Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:30:18 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:30:18 garage volumio[1198]: info: CoreCommandRouter::volumioStop Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::stop Jan 08 22:30:18 garage volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 22:30:18 garage bluetoothd[840]: Path / reserved for Adv Monitor app :1.56 Jan 08 22:30:18 garage bluetoothd[840]: Adv Monitor app :1.56 disconnected from D-Bus Jan 08 22:30:19 garage volumio[1198]: info: MPD Permissions set Jan 08 22:30:19 garage volumio[1198]: info: Disabling MyMusic plugin cd_controller Jan 08 22:30:19 garage volumio[1198]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller Jan 08 22:30:19 garage volumio[1198]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Jan 08 22:30:19 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 22:30:19 garage volumio[1198]: info: Stopping UDEV Watcher for CD Jan 08 22:30:19 garage volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 08 22:30:19 garage volumio[1198]: info: Disabling MyMusic plugin qobuzconnect Jan 08 22:30:19 garage volumio[1198]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service qobuzconnect Jan 08 22:30:19 garage volumio[1198]: info: QobuzConnect: setDeactiveState invoked Jan 08 22:30:19 garage volumio[1198]: info: CoreCommandRouter::volumioGetState Jan 08 22:30:19 garage volumio[1198]: info: CorePlayQueue::getTrack 0 Jan 08 22:30:19 garage volumio[1198]: info: Disabling plugin cd_controller Jan 08 22:30:19 garage sudo[3248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect Jan 08 22:30:19 garage sudo[3248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 22:30:19 garage volumio[1198]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered off Jan 08 22:30:19 garage volumio[1198]: info: Disabling plugin bluetooth Jan 08 22:30:19 garage sudo[3241]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:19 garage volumio[1198]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Jan 08 22:30:20 garage volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 22:30:20 garage qobuz-connect[2998]: 20260108 22:30:20.373 [2998.2998] INFO SampleApp: shat down connection on UNIX socket Jan 08 22:30:20 garage systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 08 22:30:20 garage systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 08 22:30:21 garage sudo[3248]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:21 garage volumio[1198]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Jan 08 22:30:21 garage volumio[1198]: code: 'ERR_UNHANDLED_REJECTION' Jan 08 22:30:21 garage volumio[1198]: } Jan 08 22:30:21 garage volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 22:30:22 garage systemd[1]: mpd.service: Deactivated successfully. Jan 08 22:30:22 garage systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 08 22:30:22 garage systemd[1]: mpd.service: Consumed 6.234s CPU time. Jan 08 22:30:22 garage systemd[1]: mpd.socket: Deactivated successfully. Jan 08 22:30:22 garage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 08 22:30:22 garage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 08 22:30:22 garage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 08 22:30:22 garage systemd[1]: Starting mpd.service - Music Player Daemon... Jan 08 22:30:23 garage sudo[3254]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 08 22:30:23 garage sudo[3254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 08 22:30:23 garage sudo[3254]: pam_unix(sudo:session): session closed for user root Jan 08 22:30:36 garage sudo[3298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-08 22:29' Jan 08 22:30:36 garage sudo[3298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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"