Aug 29 19:43:25 volumio-16 ntpd[1182]: CLOCK: time stepped by 110.102764 Aug 29 19:43:25 volumio-16 ntpd[1182]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 29 19:43:29 volumio-16 volumio[1382]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding inputs REST Endpoints Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 29 19:43:29 volumio-16 volumio[1382]: info: Scanning Audio Inputs Aug 29 19:43:29 volumio-16 volumio[1382]: info: Checking against Known Cards name Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:43:29 volumio-16 volumio[1382]: info: [1788047009129] CoreMusicLibrary::Adding element Loopback Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:43:29 volumio-16 volumio[1382]: Cannot find translation for source Loopback Aug 29 19:43:29 volumio-16 volumio[1382]: info: Checking against Known Cards name Aug 29 19:43:29 volumio-16 volumio[1382]: info: Checking against Known Cards name Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Server instance for streaming Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 29 19:43:29 volumio-16 volumio[1382]: error: Hi Res Audio Failed Login: Missing Login Data Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Refreshing TIDAL token Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 29 19:43:29 volumio-16 volumio[1382]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 29 19:43:29 volumio-16 sudo[2215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:43:29 volumio-16 sudo[2215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:43:29 volumio-16 volumio[1382]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 29 19:43:29 volumio-16 volumio[1382]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 29 19:43:29 volumio-16 volumio[1382]: info: Stopping AccessToken refresher cron for QOBUZ Aug 29 19:43:29 volumio-16 sudo[2222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 29 19:43:29 volumio-16 sudo[2222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:43:29 volumio-16 volumio[1382]: info: AccessToken refresher cron started for QOBUZ Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding QOBUZ REST API Endpoints Aug 29 19:43:29 volumio-16 volumio[1382]: info: Updating MyVolumio device info Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: Updating MyVolumio device info Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: Successfully Added MyVolumio device Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::getQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::ClearQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::stop Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::clearPlayQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:29 volumio-16 sudo[2222]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:29 volumio-16 sudo[2215]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29621700 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29621700 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Refreshing TIDAL token Aug 29 19:43:29 volumio-16 sudo[2224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 29 19:43:29 volumio-16 volumio[1382]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 19:43:29 volumio-16 sudo[2224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:43:29 volumio-16 volumio[1382]: info: MPD Permissions set Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 29 19:43:29 volumio-16 sudo[2224]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:29 volumio-16 volumio[1382]: error: updateQueue error: null Aug 29 19:43:29 volumio-16 volumio[1382]: info: Executing endpoint qc_getconfig Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.302 [2227.2227] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 29 19:43:29 volumio-16 volumio[1382]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 29 19:43:29 volumio-16 volumio[1382]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 29 19:43:29 volumio-16 sudo[2242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 29 19:43:29 volumio-16 sudo[2242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:43:29 volumio-16 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. Aug 29 19:43:29 volumio-16 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. Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.331 [2227.2227] INFO VolumeManager: [0xfb0260]: Setting new playback volume: 75 Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.331 [2227.2227] INFO VolumeManager: [0xfb0260]: Setting new mute state: 0 Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.331 [2227.2227] INFO AudioStreamManager: [0xfaffb8]: Setting new audio download buffer size: 1048576 Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.331 [2227.2227] INFO QobuzConnect: [0xfb0b28]: Client initialized! Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.331 [2227.2227] INFO SampleApp: Starting Avahi advertising, name: Volumio 16, service name: _qobuz-connect._tcp Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.335 [2227.2227] INFO LocalConfigManager: [0xfafce0]: Starting Local Configuration server Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.335 [2227.2227] INFO SampleApp: Starting Local configuration server Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.335 [2227.2227] INFO SampleApp: Connected to UNIX socket client 0xf9a818 Aug 29 19:43:29 volumio-16 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 29 19:43:29 volumio-16 sudo[2242]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:29 volumio-16 volumio[1382]: info: Remote SSH Started Aug 29 19:43:29 volumio-16 autossh[2247]: port set to 0, monitoring disabled Aug 29 19:43:29 volumio-16 autossh[2247]: starting ssh (count 1) Aug 29 19:43:29 volumio-16 autossh[2247]: ssh child pid is 2250 Aug 29 19:43:29 volumio-16 volumio[1382]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 29 19:43:29 volumio-16 volumiossh-tunnel[2250]: Warning: Permanently added '[us3.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Successfully Updated MyVolumio device Aug 29 19:43:29 volumio-16 qobuz-connect[2227]: 20260829 19:43:29.452 [2227.2227] INFO SampleApp: Playback volume changed: 75 Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Access Token successfully retrieved Aug 29 19:43:29 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::ClearQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::stop Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::clearPlayQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29621700 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29621700 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Successfully retrieved User Session From TIDAL Aug 29 19:43:29 volumio-16 volumio[1382]: info: Successfully Updated MyVolumio device Aug 29 19:43:29 volumio-16 volumio[1382]: info: Successfully retrieved User Subscription From TIDAL Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding TIDAL to Browse Sources Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:43:29 volumio-16 volumio[1382]: info: [1788047009737] CoreMusicLibrary::Adding element TIDAL Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:43:29 volumio-16 volumio[1382]: Cannot find translation for source Loopback Aug 29 19:43:29 volumio-16 volumio[1382]: Cannot find translation for source TIDAL Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding TIDAL REST API Endpoints Aug 29 19:43:29 volumio-16 volumio[1382]: info: explodeTIDALUri took 291 milliseconds Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::updateTrackBlock Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrackBlock Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPlay Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::play index 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::addQueueItems Aug 29 19:43:29 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/22074824 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/22074824 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/71815215 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/71815215 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/30573150 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/30573150 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197083052 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197083052 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/44001419 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/44001419 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/181717308 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/181717308 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626928 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626928 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/46876615 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/46876615 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197037692 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197037692 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197519569 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197519569 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/35851341 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/35851341 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120611259 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120611259 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120620676 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120620676 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/345875012 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/345875012 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626923 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626923 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626924 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626924 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29498522 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29498522 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/51913245 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/51913245 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/49143832 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/49143832 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626925 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626925 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/7231794 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/7231794 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/498818578 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/498818578 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/194359089 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/194359089 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20827610 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20827610 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/64450959 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/64450959 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/24282513 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/24282513 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/81202884 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/81202884 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/441730245 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/441730245 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/463334637 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/463334637 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20830286 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20830286 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/121279510 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/121279510 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/28917601 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/28917601 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20871501 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20871501 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/205105117 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/205105117 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/283235788 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/283235788 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626927 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626927 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/65537409 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/65537409 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/46119888 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/46119888 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/69979425 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/69979425 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29850866 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29850866 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29498522 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29498522 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/65564189 Aug 29 19:43:29 volumio-16 volumio[1382]: info: Exploding uri tidal://song/65564189 in service tidal Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::stop Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::play index undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreStateMachine::startPlaybackTimer Aug 29 19:43:29 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:43:29 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 19:43:29 volumio-16 volumio[1382]: info: [1788047009928] ControllerTidal::clearAddPlayTrack Aug 29 19:43:29 volumio-16 volumio[1382]: info: Getting stream with soundQuality LOSSLESS Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 85 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 356 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::updateTrackBlock Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::getTrackBlock Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPlay Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::play index 0 Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::addQueueItems Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::addQueueItems Aug 29 19:43:30 volumio-16 volumio[1382]: info: Preload queue cleared Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/22074824 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Using cached record of: tidal://song/22074824 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/71815215 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/71815215 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/30573150 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/30573150 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197083052 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197083052 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/44001419 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/44001419 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/181717308 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/181717308 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626928 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626928 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/46876615 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/46876615 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197037692 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197037692 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/197519569 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/197519569 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/35851341 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/35851341 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120611259 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120611259 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120620676 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120620676 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/345875012 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/345875012 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626923 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626923 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626924 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626924 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29498522 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29498522 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/51913245 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/51913245 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/49143832 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/49143832 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626925 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626925 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/7231794 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/7231794 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/498818578 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/498818578 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/194359089 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/194359089 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20827610 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20827610 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/64450959 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/64450959 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/24282513 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/24282513 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/81202884 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/81202884 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/441730245 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/441730245 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/463334637 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/463334637 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20830286 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20830286 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/121279510 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/121279510 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/28917601 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/28917601 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/20871501 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/20871501 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/205105117 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/205105117 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/283235788 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/283235788 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/120626927 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/120626927 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/65537409 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/65537409 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/46119888 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/46119888 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/69979425 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/69979425 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29850866 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29850866 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/29498522 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/29498522 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: Adding Item to queue: tidal://song/65564189 Aug 29 19:43:30 volumio-16 volumio[1382]: info: Exploding uri tidal://song/65564189 in service tidal Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::stop Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::play index undefined Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreStateMachine::startPlaybackTimer Aug 29 19:43:30 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:43:30 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 19:43:30 volumio-16 volumio[1382]: info: [1788047010020] ControllerTidal::clearAddPlayTrack Aug 29 19:43:30 volumio-16 volumio[1382]: info: Getting stream with soundQuality LOSSLESS Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 83 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 84 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 501 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 502 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 503 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 502 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 502 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: info: explodeTIDALUri took 503 milliseconds Aug 29 19:43:30 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626928] not found"} Aug 29 19:43:30 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626928 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626928] not found"} Aug 29 19:43:30 volumio-16 sshd[2265]: Accepted password for volumio from 192.168.50.156 port 62073 ssh2 Aug 29 19:43:30 volumio-16 sshd[2265]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Aug 29 19:43:30 volumio-16 systemd-logind[982]: New session 1 of user volumio. Aug 29 19:43:31 volumio-16 systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Aug 29 19:43:31 volumio-16 systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Aug 29 19:43:31 volumio-16 systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Aug 29 19:43:31 volumio-16 systemd[1]: Starting user@1000.service - User Manager for UID 1000... Aug 29 19:43:31 volumio-16 (systemd)[2268]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Aug 29 19:43:31 volumio-16 systemd[2268]: Queued start job for default target default.target. Aug 29 19:43:31 volumio-16 systemd[2268]: Created slice app.slice - User Application Slice. Aug 29 19:43:31 volumio-16 systemd[2268]: Reached target paths.target - Paths. Aug 29 19:43:31 volumio-16 systemd[2268]: Reached target timers.target - Timers. Aug 29 19:43:31 volumio-16 systemd[2268]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Aug 29 19:43:31 volumio-16 systemd[2268]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 29 19:43:31 volumio-16 systemd[2268]: Reached target sockets.target - Sockets. Aug 29 19:43:31 volumio-16 systemd[2268]: Reached target basic.target - Basic System. Aug 29 19:43:31 volumio-16 systemd[1]: Started user@1000.service - User Manager for UID 1000. Aug 29 19:43:31 volumio-16 systemd[1]: Started session-1.scope - Session 1 of User volumio. Aug 29 19:43:31 volumio-16 systemd[2268]: Started mpris-proxy.service - Bluetooth mpris proxy. Aug 29 19:43:31 volumio-16 systemd[2268]: Reached target default.target - Main User Target. Aug 29 19:43:31 volumio-16 systemd[2268]: Startup finished in 226ms. Aug 29 19:43:31 volumio-16 systemd[2268]: Created slice session.slice - User Core Session Slice. Aug 29 19:43:31 volumio-16 systemd[2268]: Starting dbus.service - D-Bus User Message Bus... Aug 29 19:43:31 volumio-16 systemd[2268]: Started dbus.service - D-Bus User Message Bus. Aug 29 19:43:31 volumio-16 sshd[2265]: pam_env(sshd:session): deprecated reading of user environment enabled Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1323 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1323 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1324 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1325 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1325 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1325 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1327 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626927] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626927 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626927] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1327 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: getStreamUrl took 1326 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1328 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1329 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1329 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1428 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1428 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1428 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1428 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1429 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626923] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626923 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626923] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1429 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626924] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626924 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626924] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1430 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1430 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1430 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1430 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1431 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626925] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626925 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626925] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1432 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1432 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1433 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1432 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1433 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1433 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1434 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1434 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1435 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1436 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1435 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1436 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1437 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1437 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1442 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1443 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626927] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626927 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626927] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1444 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1444 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1445 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1445 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1447 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1448 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: getStreamUrl took 1447 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1362 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1364 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1364 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1364 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1366 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1367 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1368 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626928] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626928 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626928] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1369 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1369 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1370 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1371 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626924] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626924 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626924] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1372 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626923] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626923 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626923] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1372 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1374 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1375 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1375 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626925] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: error: Commandrouter: Cannot explode uri tidal://song/120626925 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [120626925] not found"} Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1375 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1377 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1377 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1379 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1380 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1380 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1380 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1382 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1383 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1383 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1482 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1482 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::updateTrackBlock Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::getTrackBlock Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1396 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand stop took 67 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand stop took 39 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 19:43:31 volumio-16 volumio[1382]: info: explodeTIDALUri took 1397 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushQueue Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::saveQueue Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::updateTrackBlock Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::getTrackBlock Aug 29 19:43:31 volumio-16 volumio[1382]: info: Aug 29 19:43:31 volumio-16 volumio[1382]: ---------------------------- MPD announces system playlist update Aug 29 19:43:31 volumio-16 volumio[1382]: info: Ignoring MPD Status Update Aug 29 19:43:31 volumio-16 volumio[1382]: info: Aug 29 19:43:31 volumio-16 volumio[1382]: ---------------------------- MPD announces system playlist update Aug 29 19:43:31 volumio-16 volumio[1382]: info: Ignoring MPD Status Update Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand clear took 25 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand clear took 26 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==" Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==" Aug 29 19:43:31 volumio-16 volumio[1382]: error: updateQueue error: null Aug 29 19:43:31 volumio-16 volumio[1382]: error: updateQueue error: null Aug 29 19:43:31 volumio-16 volumio[1382]: info: Aug 29 19:43:31 volumio-16 volumio[1382]: ---------------------------- MPD announces system playlist update Aug 29 19:43:31 volumio-16 volumio[1382]: info: Ignoring MPD Status Update Aug 29 19:43:31 volumio-16 volumio[1382]: info: ------------------------------ 4ms Aug 29 19:43:31 volumio-16 volumio[1382]: info: ------------------------------ 4ms Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==" took 3 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==" took 3 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand play Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand play Aug 29 19:43:31 volumio-16 volumio[1382]: info: ------------------------------ 1ms Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand play took 3 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand play took 3 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: Aug 29 19:43:31 volumio-16 volumio[1382]: ---------------------------- MPD announces state update: player Aug 29 19:43:31 volumio-16 volumio[1382]: info: ControllerMpd::getState Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand status Aug 29 19:43:31 volumio-16 volumio[1382]: info: Aug 29 19:43:31 volumio-16 volumio[1382]: ---------------------------- MPD announces state update: player Aug 29 19:43:31 volumio-16 volumio[1382]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Unknown error 524 Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand status took 22 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: ControllerMpd::getState Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand status Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::parseState Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand clearerror took 2 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand status took 2 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::parseState Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::parseTrackInfo Aug 29 19:43:31 volumio-16 volumio[1382]: info: ControllerMpd::pushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::servicePushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==","trackType":"tidal"} Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: CURRENT POSITION 0 Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::syncState stateService pause Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::syncState currentStatus stop Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:31 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:43:31.549-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:43:31 volumio-16 volumio[1382]: info: ------------------------------ 32ms Aug 29 19:43:31 volumio-16 volumio[1382]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: ControllerMpd::parseTrackInfo Aug 29 19:43:31 volumio-16 volumio[1382]: info: ControllerMpd::pushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::servicePushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczMWQ3N2RlNzlmOGM3YjI0ZDQyMDRkOTMyZWNlNTI3MV82MS5tcDQ/0.flac?token=1788050610~NzY5N2NhMDIyMjI5MWE1NTA0M2I2YzU2M2Y0NDVmNTQ3Mzc3YmRkYw==","trackType":"tidal"} Aug 29 19:43:31 volumio-16 volumio[1382]: verbose: CURRENT POSITION 0 Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::syncState stateService pause Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::syncState currentStatus stop Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:43:31 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:31 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:43:31.556-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:43:31 volumio-16 volumio[1382]: info: ------------------------------ 21ms Aug 29 19:43:31 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status Aug 29 19:43:31 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 29 19:43:31 volumio-16 volumio[1382]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JwXQCPfTHfVbyPxct0htBoxG6tF3.f5b22f875d0602b60e7a5f89c2426a43.state.status' Aug 29 19:43:32 volumio-16 volumio[1382]: info: TidalConnect service stoped! Aug 29 19:43:32 volumio-16 volumio[1382]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:43:32 volumio-16 volumio[1382]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:43:32 volumio-16 sudo[2311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 29 19:43:32 volumio-16 sudo[2311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:43:32 volumio-16 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 29 19:43:32 volumio-16 sudo[2311]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:32 volumio-16 volumio[1382]: info: Executing endpoint tc_getconfig Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 29 19:43:32 volumio-16 vtcs[2314]: STARTING TidalConnect services, version: 1.6.1 Aug 29 19:43:32 volumio-16 vtcs[2314]: STARTED TidalConnect services. Aug 29 19:43:32 volumio-16 volumio[1382]: info: Executing endpoint tc_connect Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 29 19:43:32 volumio-16 volumio[1382]: info: Connecting to TidalConnect Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::servicePushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:32 volumio-16 volumio[1382]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::servicePushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:32 volumio-16 volumio[1382]: info: CorePlayQueue::getTrack 0 Aug 29 19:43:32 volumio-16 volumio[1382]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 29 19:43:32 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:43:32.341-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:43:32 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:43:32.341-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:43:32 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status Aug 29 19:43:32 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 29 19:43:32 volumio-16 volumio[1382]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JwXQCPfTHfVbyPxct0htBoxG6tF3.f5b22f875d0602b60e7a5f89c2426a43.state.status' Aug 29 19:43:32 volumio-16 systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:43:33 volumio-16 volumio[1382]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 19:43:33 volumio-16 volumio[1382]: info: Received Get System Version Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:43:33 volumio-16 volumio[1382]: info: Received Get System Info Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:43:33 volumio-16 volumio[1382]: info: Discovery: Getting this device information Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:33 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:43:33 volumio-16 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 29 19:43:34 volumio-16 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 29 19:43:34 volumio-16 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 19:43:34 volumio-16 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 19:43:34 volumio-16 sudo[2089]: pam_unix(sudo:session): session closed for user root Aug 29 19:43:34 volumio-16 volumio[1382]: info: Upmpdcli Daemon Started Aug 29 19:43:35 volumio-16 volumio[1382]: info: TidalConnect service started! Aug 29 19:43:35 volumio-16 volumio[1382]: [Metrics] CommandRouter: 26s 494.53ms Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:43:35 volumio-16 volumio[1382]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 29 19:43:35 volumio-16 volumio[1382]: aplay: main:831: audio open error: Unknown error 524 Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:43:35 volumio-16 volumio[1382]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 29 19:43:35 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:36 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 19:43:36 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:43:36 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:43:40 volumio-16 volumio-remote-updater[989]: Test mode disabled Aug 29 19:43:40 volumio-16 volumio-remote-updater[989]: Alpha mode disabled Aug 29 19:43:40 volumio-16 volumio-remote-updater[989]: Alpha legacy test mode disabled Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:43:40 volumio-16 volumio[1382]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:43:40 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:43:42 volumio-16 volumio[1382]: info: BOOT COMPLETED Aug 29 19:43:47 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:43:49 volumio-16 volumio[1382]: info: Executing endpoint metavolumio Aug 29 19:43:49 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 19:43:54 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 19:43:54 volumio-16 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 29 19:43:55 volumio-16 systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 29 19:43:55 volumio-16 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 29 19:44:01 volumio-16 upmpdcli[2436]: writing RSA key Aug 29 19:44:45 volumio-16 volumio[1382]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 19:44:45 volumio-16 volumio[1382]: info: Preparing to save Alsa Options, stopping services first Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:44:45 volumio-16 volumio[1382]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"HDMI 1 Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: Enabling I2S DAC: HiFiBerry Digi+ Pro Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 sudo[2525]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 29 19:44:45 volumio-16 sudo[2525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 sudo[2525]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:45 volumio-16 volumio[1382]: info: No Overlays Loaded Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 sudo[2528]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-digi-pro Aug 29 19:44:45 volumio-16 sudo[2528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 volumio[1382]: warn: Unable to locate the device HDMI 1 Out a reboot may be required. Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/status Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#address-cells Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#size-cells Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/clock44-gpio Aug 29 19:44:45 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/clock48-gpio Aug 29 19:44:45 volumio-16 sudo[2528]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: Invalid card number '2'. Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 19:44:45 volumio-16 volumio[1382]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 19:44:45 volumio-16 volumio[1382]: info: Disabling external Volume Control Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 29 19:44:45 volumio-16 volumio[1382]: info: Preparing to generate the ALSA configuration file Aug 29 19:44:45 volumio-16 volumio[1382]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Aug 29 19:44:45 volumio-16 volumio[1382]: info: I2S Param [object Object] successfully enabled Aug 29 19:44:45 volumio-16 kernel: wm8804 1-003b: Failed to read device ID: -121 Aug 29 19:44:45 volumio-16 kernel: wm8804 1-003b: probe with driver wm8804 failed with error -121 Aug 29 19:44:45 volumio-16 volumio[1382]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 29 19:44:45 volumio-16 volumio[1382]: info: Reading ALSA contributions from plugins. Aug 29 19:44:45 volumio-16 volumio[1382]: info: VolumeController:: Volume=undefined Mute =false Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:44:45 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:44:45.829-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:44:45 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:44:45 volumio-16 volumio[1382]: info: Getting Alsa Cards List without I2S DAC Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: Invalid card number '2'. Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 19:44:45 volumio-16 volumio[1382]: info: Asound.conf file written Aug 29 19:44:45 volumio-16 sudo[2575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 19:44:45 volumio-16 sudo[2575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 sudo[2575]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:45 volumio-16 volumio[1382]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 29 19:44:45 volumio-16 volumio[1382]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 29 19:44:45 volumio-16 volumio[1382]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Aug 29 19:44:45 volumio-16 volumio[1382]: info: Output device has changed, restarting MPD Aug 29 19:44:45 volumio-16 sudo[2581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:44:45 volumio-16 sudo[2581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 volumio[1382]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:45 volumio-16 sudo[2581]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:45 volumio-16 volumio[1382]: info: QobuzConnect: setDeactiveState invoked Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:44:45 volumio-16 vtcs[2314]: [2026-08-29 19:44:45.958] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 29 19:44:45 volumio-16 vtcs[2314]: [2026-08-29 19:44:45.958] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 29 19:44:45 volumio-16 sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:44:45 volumio-16 sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 volumio[1382]: info: Output device has changed, restarting MPD Aug 29 19:44:45 volumio-16 volumio[1382]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:45 volumio-16 sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:44:45 volumio-16 sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 sudo[2591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:44:45 volumio-16 sudo[2591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 volumio[1382]: info: QobuzConnect: setDeactiveState invoked Aug 29 19:44:45 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:44:45 volumio-16 sudo[2591]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:45 volumio-16 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 19:44:45 volumio-16 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 29 19:44:45 volumio-16 sudo[2593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:44:45 volumio-16 systemd[1]: vtcs.service: Deactivated successfully. Aug 29 19:44:45 volumio-16 sudo[2593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:45 volumio-16 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 29 19:44:46 volumio-16 sudo[2589]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:44:46 volumio-16 sudo[2599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 sudo[2603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:44:46 volumio-16 sudo[2603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:44:46 volumio-16 sudo[2599]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 sudo[2603]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 29 19:44:46 volumio-16 sudo[2617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 29 19:44:46 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 19:44:46 volumio-16 sudo[2608]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 29 19:44:46 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 19:44:46 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 19:44:46 volumio-16 volumio[1382]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 19:44:46 volumio-16 volumio[1382]: info: MPD Permissions set Aug 29 19:44:46 volumio-16 volumio[1382]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 19:44:46 volumio-16 volumio[1382]: info: MPD Permissions set Aug 29 19:44:46 volumio-16 sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 29 19:44:46 volumio-16 sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 19:44:46 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 sudo[2625]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2617]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 29 19:44:46 volumio-16 sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 sudo[2634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 29 19:44:46 volumio-16 sudo[2634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:44:46 volumio-16 volumio[1382]: info: VolumeController::SetAlsaVolume0 Aug 29 19:44:46 volumio-16 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreStateMachine::pushState Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioPushState Aug 29 19:44:46 volumio-16 qobuz-connect[2227]: 20260829 19:44:46.166 [2227.2227] INFO SampleApp: Stopping Local configuration server Aug 29 19:44:46 volumio-16 volumio[1382]: info: CoreCommandRouter::volumioGetState Aug 29 19:44:46 volumio-16 volumio5-onboarding[1595]: time=2026-08-29T19:44:46.171-04:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 19:44:46 volumio-16 volumio[1382]: info: PeppyMeterBasic ---peppymeterbasic status Aug 29 19:44:46 volumio-16 volumio[1382]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Aug 29 19:44:46 volumio-16 sudo[2632]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:44:46 volumio-16 sudo[2632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 19:44:46 volumio-16 sudo[2632]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 mpd[2645]: 2026-08-29T19:44:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:44:46 volumio-16 systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 19:44:46 volumio-16 sudo[2583]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 sudo[2593]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:46 volumio-16 volumio[1382]: error: MPD error: The expression evaluated to a falsy value: Aug 29 19:44:46 volumio-16 volumio[1382]: assert.ok(self.idling) Aug 29 19:44:46 volumio-16 volumio[1382]: error: The expression evaluated to a falsy value: Aug 29 19:44:46 volumio-16 volumio[1382]: assert.ok(self.idling) Aug 29 19:44:46 volumio-16 volumio[1382]: error: updateQueue error: null Aug 29 19:44:47 volumio-16 volumio[1382]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 29 19:44:47 volumio-16 volumio[1382]: info: PLUGIN onReboot : networkfs Aug 29 19:44:47 volumio-16 volumio[1382]: info: PLUGIN onReboot : touch_display Aug 29 19:44:47 volumio-16 sudo[2653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Aug 29 19:44:47 volumio-16 sudo[2653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:47 volumio-16 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Aug 29 19:44:47 volumio-16 sudo[2659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Aug 29 19:44:47 volumio-16 sudo[2659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:47 volumio-16 startx[1760]: xinit: connection to X server lost Aug 29 19:44:47 volumio-16 startx[1760]: Aug 29 19:44:47 volumio-16 systemd-logind[982]: The system will reboot now! Aug 29 19:44:47 volumio-16 systemd-logind[982]: System is rebooting. Aug 29 19:44:47 volumio-16 sudo[2634]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:47 volumio-16 sudo[2636]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:47 volumio-16 volumio[1382]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Aug 29 19:44:47 volumio-16 volumio[1382]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:44:47 volumio-16 volumio[1382]: [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".] { Aug 29 19:44:47 volumio-16 volumio[1382]: code: 'ERR_UNHANDLED_REJECTION' Aug 29 19:44:47 volumio-16 volumio[1382]: } Aug 29 19:44:47 volumio-16 volumio[1382]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:44:47 volumio-16 sudo[2659]: pam_unix(sudo:session): session closed for user root Aug 29 19:44:47 volumio-16 sshd[2265]: pam_unix(sshd:session): session closed for user volumio Aug 29 19:44:47 volumio-16 systemd[1]: Stopping session-1.scope - Session 1 of User volumio... Aug 29 19:44:47 volumio-16 systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target graphical.target - Graphical Interface. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target multi-user.target - Multi-User System. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target getty.target - Login Prompts. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target rpc_pipefs.target. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target timers.target - Timer Units. Aug 29 19:44:47 volumio-16 systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Aug 29 19:44:47 volumio-16 systemd[1]: apt-daily.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Aug 29 19:44:47 volumio-16 systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Aug 29 19:44:47 volumio-16 systemd[1]: e2scrub_all.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Aug 29 19:44:47 volumio-16 systemd[1]: fstrim.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Aug 29 19:44:47 volumio-16 systemd[1]: man-db.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Aug 29 19:44:47 volumio-16 systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Aug 29 19:44:47 volumio-16 systemd[1]: setdatetime-helper.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Aug 29 19:44:47 volumio-16 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 29 19:44:47 volumio-16 systemd[1]: systemd-rfkill.socket: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 29 19:44:47 volumio-16 systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping bluealsa.service - BlueALSA service... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping cpufrequtils.service - LSB: set CPUFreq kernel parameters... Aug 29 19:44:47 volumio-16 systemd[1]: glamor-test.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 bluealsa[1103]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Aug 29 19:44:47 volumio-16 systemd[1]: Stopped glamor-test.service - Check for glamor. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping ifplugd.service - LSB: Brings up/down network automatically... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 19:44:47 volumio-16 ntpd[1182]: ERR: ntpd exiting on signal 15 (Terminated) Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 216.232.132.95 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 51.222.111.13 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 158.69.193.108 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 173.206.39.180 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 198.181.199.84 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 216.232.132.18 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 142.4.192.253 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 155.138.134.195 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 216.232.132.102 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 23.159.16.194 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 167.160.187.179 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 138.197.135.239 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 207.210.46.249 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 162.159.200.1 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 149.56.19.163 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 ntpd[1182]: PROTO: 142.162.168.195 unlink local addr 192.168.50.161 -> Aug 29 19:44:47 volumio-16 systemd[1]: Stopping ntpsec.service - Network Time Service... Aug 29 19:44:47 volumio-16 systemd[1]: plymouth-quit.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 29 19:44:47 volumio-16 systemd[1]: rp1-test.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped rp1-test.service - Check for RP1 displays for Xorg. Aug 29 19:44:47 volumio-16 systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Aug 29 19:44:47 volumio-16 systemd[1]: rpi-eeprom-update.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 19:44:47 volumio-16 bluetoothd[946]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 29 19:44:47 volumio-16 systemd[1]: Stopping smbd.service - Samba SMB Daemon... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Aug 29 19:44:47 volumio-16 sshd[1226]: Received signal 15; terminating. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Aug 29 19:44:47 volumio-16 bluetoothd[946]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 29 19:44:47 volumio-16 systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Aug 29 19:44:47 volumio-16 autossh[2247]: received signal to exit (15) Aug 29 19:44:47 volumio-16 bluetoothd[946]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 29 19:44:47 volumio-16 systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Aug 29 19:44:47 volumio-16 bluetoothd[946]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 29 19:44:47 volumio-16 systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping upower.service - Daemon for power management... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping volumio-ipchange.service - Volumio IP Change Monitor... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping volumio-remote-updater.service - Volumio Remote OTA Updater... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Aug 29 19:44:47 volumio-16 systemd[1]: welcome.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 29 19:44:47 volumio-16 systemd[1]: volumio-remote-updater.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Aug 29 19:44:47 volumio-16 systemd[1]: bluealsa.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped bluealsa.service - BlueALSA service. Aug 29 19:44:47 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 19:44:47 volumio-16 systemd[1]: shairport-sync.service: Consumed 1.717s CPU time. Aug 29 19:44:47 volumio-16 systemd[1]: volumio-ipchange.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped volumio-ipchange.service - Volumio IP Change Monitor. Aug 29 19:44:47 volumio-16 systemd[1]: ntpsec.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped ntpsec.service - Network Time Service. Aug 29 19:44:47 volumio-16 systemd[1]: ssh.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Aug 29 19:44:47 volumio-16 systemd[1]: upower.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped upower.service - Daemon for power management. Aug 29 19:44:47 volumio-16 systemd[1]: serial-getty@ttyAMA10.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Aug 29 19:44:47 volumio-16 systemd[1]: volumio5-onboarding.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 29 19:44:47 volumio-16 systemd[1]: smbd.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped smbd.service - Samba SMB Daemon. Aug 29 19:44:47 volumio-16 systemd[1]: sshtunnel.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 29 19:44:47 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 19:44:47 volumio-16 systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Aug 29 19:44:47 volumio-16 systemd[1]: systemd-random-seed.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Aug 29 19:44:47 volumio-16 systemd[1]: session-1.scope: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped session-1.scope - Session 1 of User volumio. Aug 29 19:44:47 volumio-16 systemd-logind[982]: Session 1 logged out. Waiting for processes to exit. Aug 29 19:44:47 volumio-16 systemd[1]: Removed slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped target sound.target - Sound Card. Aug 29 19:44:47 volumio-16 avahi-daemon[945]: Got SIGTERM, quitting. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 19:44:47 volumio-16 avahi-daemon[945]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.50.161. Aug 29 19:44:47 volumio-16 bluetoothd[946]: Terminating Aug 29 19:44:47 volumio-16 systemd[1]: Stopping bluetooth.service - Bluetooth service... Aug 29 19:44:47 volumio-16 avahi-daemon[945]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 19:44:47 volumio-16 systemd[1]: plymouth-quit-wait.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped plymouth-quit-wait.service - Hold until boot process finishes up. Aug 29 19:44:47 volumio-16 qobuz-connect[2227]: 20260829 19:44:47.412 [2227.2227] INFO SampleApp: shat down connection on UNIX socket Aug 29 19:44:47 volumio-16 systemd[1]: rc-local.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped rc-local.service - /etc/rc.local Compatibility. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping systemd-logind.service - User Login Management... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Aug 29 19:44:47 volumio-16 systemd[1]: Stopping winbind.service - Samba Winbind Daemon... Aug 29 19:44:47 volumio-16 winbindd[2057]: [2026/08/29 19:44:47.415494, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 29 19:44:47 volumio-16 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 winbindd[2057]: Got sig[15] terminate (is_parent=0) Aug 29 19:44:47 volumio-16 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 29 19:44:47 volumio-16 winbindd[2042]: [2026/08/29 19:44:47.416575, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 29 19:44:47 volumio-16 systemd[2268]: Activating special unit exit.target... Aug 29 19:44:47 volumio-16 winbindd[2042]: Got sig[15] terminate (is_parent=1) Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped target default.target - Main User Target. Aug 29 19:44:47 volumio-16 winbindd[2078]: [2026/08/29 19:44:47.418141, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 29 19:44:47 volumio-16 systemd[2268]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Aug 29 19:44:47 volumio-16 winbindd[2078]: Got sig[15] terminate (is_parent=0) Aug 29 19:44:47 volumio-16 systemd[1]: cpufrequtils.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped cpufrequtils.service - LSB: set CPUFreq kernel parameters. Aug 29 19:44:47 volumio-16 ifplugd(eth0)[1206]: Exiting. Aug 29 19:44:47 volumio-16 bluetoothd[946]: Destroy Adv Monitor Manager Aug 29 19:44:47 volumio-16 bluetoothd[946]: Battery Provider Manager destroyed Aug 29 19:44:47 volumio-16 bluetoothd[946]: Stopping SDP server Aug 29 19:44:47 volumio-16 bluetoothd[946]: Exit Aug 29 19:44:47 volumio-16 systemd-logind[982]: Removed session 1. Aug 29 19:44:47 volumio-16 startx[1760]: waiting for X server to shut down Aug 29 19:44:47 volumio-16 startx[1761]: (II) Server terminated successfully (0). Closing log file. Aug 29 19:44:47 volumio-16 mpris-proxy[2284]: org.bluez appeared Aug 29 19:44:47 volumio-16 mpris-proxy[2284]: Bluetooth Adapter /org/bluez/hci0 found Aug 29 19:44:47 volumio-16 mpris-proxy[2284]: Bluetooth Adapter /org/bluez/hci0 removed Aug 29 19:44:47 volumio-16 mpris-proxy[2284]: org.bluez disappeared Aug 29 19:44:47 volumio-16 avahi-daemon[945]: avahi-daemon 0.8 exiting. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Aug 29 19:44:47 volumio-16 systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 19:44:47 volumio-16 systemd[1]: bluetooth.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped bluetooth.service - Bluetooth service. Aug 29 19:44:47 volumio-16 systemd[1]: winbind.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped winbind.service - Samba Winbind Daemon. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopping dbus.service - D-Bus User Message Bus... Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped dbus.service - D-Bus User Message Bus. Aug 29 19:44:47 volumio-16 systemd[2268]: Removed slice session.slice - User Core Session Slice. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped target basic.target - Basic System. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped target paths.target - Paths. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped target sockets.target - Sockets. Aug 29 19:44:47 volumio-16 systemd[2268]: Stopped target timers.target - Timers. Aug 29 19:44:47 volumio-16 systemd[2268]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 29 19:44:47 volumio-16 systemd[2268]: Closed dirmngr.socket - GnuPG network certificate management daemon. Aug 29 19:44:47 volumio-16 systemd[2268]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 29 19:44:47 volumio-16 systemd[2268]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Aug 29 19:44:47 volumio-16 systemd[2268]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Aug 29 19:44:47 volumio-16 systemd[2268]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Aug 29 19:44:47 volumio-16 systemd[2268]: Removed slice app.slice - User Application Slice. Aug 29 19:44:47 volumio-16 systemd[2268]: Reached target shutdown.target - Shutdown. Aug 29 19:44:47 volumio-16 systemd[2268]: Finished systemd-exit.service - Exit the Session. Aug 29 19:44:47 volumio-16 systemd[2268]: Reached target exit.target - Exit the Session. Aug 29 19:44:47 volumio-16 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Aug 29 19:44:47 volumio-16 nmbd[1272]: [2026/08/29 19:44:47.469763, 0] ../../source3/nmbd/nmbd.c:59(terminate) Aug 29 19:44:47 volumio-16 nmbd[1272]: Got SIGTERM: going down... Aug 29 19:44:47 volumio-16 systemd[1]: systemd-logind.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped systemd-logind.service - User Login Management. Aug 29 19:44:47 volumio-16 systemd[1]: nmbd.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Aug 29 19:44:47 volumio-16 systemd[1]: loadcpufreq.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Aug 29 19:44:47 volumio-16 sudo[2709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 19:43' Aug 29 19:44:47 volumio-16 sudo[2709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 19:44:47 volumio-16 systemd[1]: user@1000.service: Deactivated successfully. Aug 29 19:44:47 volumio-16 systemd[1]: Stopped user@1000.service - User Manager for 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"