Oct 23 15:35:01 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 23 15:35:01 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:01 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:01 volumio volumio[1143]: info: MRS: MultiRoom plugin initialized Oct 23 15:35:01 volumio volumio[1143]: info: MRS: STOPPING SNAPCLIENT Oct 23 15:35:01 volumio volumio[1143]: info: MRS: Snap server stop Oct 23 15:35:01 volumio volumio[1143]: info: MRS: STOPPING volumioStreaming Oct 23 15:35:01 volumio sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 23 15:35:01 volumio sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 23 15:35:01 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 23 15:35:01 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:01 volumio sudo[2502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 23 15:35:01 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:01 volumio sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 23 15:35:01 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:01 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 23 15:35:01 volumio sudo[2495]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:01 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 23 15:35:01 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 23 15:35:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 23 15:35:01 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Oct 23 15:35:01 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 23 15:35:01 volumio volumio[1143]: info: MRS: Removed streaming files Oct 23 15:35:01 volumio volumio[1143]: info: MRS: volumioStreaming STOPPED Oct 23 15:35:01 volumio volumio[1143]: info: MRS: SNAPSERVER STOPPED Oct 23 15:35:01 volumio volumio[1143]: info: MRS: SNAPCLIENT STOPPED Oct 23 15:35:02 volumio volumio[1143]: info: Asound.conf file unchanged, so no further update is needed Oct 23 15:35:02 volumio volumio[1143]: info: Output device has changed, restarting MPD Oct 23 15:35:02 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:02 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 15:35:02 volumio volumio[1143]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:02 volumio sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 15:35:02 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:02 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 23 15:35:02 volumio volumio[1143]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 23 15:35:02 volumio sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 15:35:02 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:02 volumio volumio[1143]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 23 15:35:02 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 23 15:35:02 volumio volumio[1143]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 23 15:35:02 volumio volumio[1143]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 23 15:35:02 volumio volumio[1143]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 23 15:35:02 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 23 15:35:02 volumio volumio[1143]: info: MRS: Pushing multiroomSync output for this device Oct 23 15:35:02 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:02 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:02 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:02 volumio volumio[1143]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:02 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:02 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 23 15:35:02 volumio volumio[1143]: info: Adding METAVOLUMIO REST API Endpoints Oct 23 15:35:02 volumio volumio[1143]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 23 15:35:02 volumio volumio[1143]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 23 15:35:02 volumio volumio[1143]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 23 15:35:02 volumio volumio[1143]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 23 15:35:02 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 23 15:35:02 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 23 15:35:02 volumio volumio[1143]: info: Preparing CD Folders Oct 23 15:35:02 volumio volumio[1143]: info: Adding CD REST API Endpoints Oct 23 15:35:02 volumio volumio[1143]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 23 15:35:02 volumio volumio[1143]: info: Starting UDEV Watcher for CD Oct 23 15:35:02 volumio volumio[1143]: info: Detecting CD presence with UDEV Oct 23 15:35:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 23 15:35:02 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 23 15:35:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 23 15:35:02 volumio systemd[1]: mpd.service: Consumed 3.076s CPU time. Oct 23 15:35:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 23 15:35:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 23 15:35:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 23 15:35:02 volumio bluetoothd[714]: Path / reserved for Adv Monitor app :1.31 Oct 23 15:35:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 23 15:35:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 23 15:35:02 volumio bluetoothd[714]: Adv Monitor app :1.31 disconnected from D-Bus Oct 23 15:35:02 volumio sudo[2533]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 23 15:35:02 volumio sudo[2533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 23 15:35:02 volumio sudo[2533]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:03 volumio mpd[2535]: 2025-10-23T15:35:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 23 15:35:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 23 15:35:03 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:07 volumio volumio[1143]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 23 15:35:07 volumio volumio[1143]: info: Adding inputs REST Endpoints Oct 23 15:35:07 volumio volumio[1143]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 23 15:35:07 volumio volumio[1143]: info: Scanning Audio Inputs Oct 23 15:35:07 volumio volumio[1143]: info: Checking against Known Cards name Oct 23 15:35:07 volumio volumio[1143]: info: Adding Server instance for streaming Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 23 15:35:07 volumio volumio[1143]: error: Hi Res Audio Failed Login: Missing Login Data Oct 23 15:35:07 volumio volumio[1143]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 23 15:35:07 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 23 15:35:07 volumio volumio[1143]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 23 15:35:07 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 15:35:07 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:07 volumio volumio[1143]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 23 15:35:07 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:07 volumio volumio[1143]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 23 15:35:07 volumio volumio[1143]: info: Stopping AccessToken refresher cron Oct 23 15:35:07 volumio sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 23 15:35:07 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:08 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:08 volumio volumio[1143]: info: AccessToken refresher cron started Oct 23 15:35:08 volumio volumio[1143]: info: Stopping AccessToken refresher cron for QOBUZ Oct 23 15:35:08 volumio sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 23 15:35:08 volumio volumio[1143]: info: AccessToken refresher cron started for QOBUZ Oct 23 15:35:08 volumio volumio[1143]: info: Adding TIDAL REST API Endpoints Oct 23 15:35:08 volumio volumio[1143]: info: Adding QOBUZ REST API Endpoints Oct 23 15:35:08 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:08 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:35:08 volumio sudo[2566]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:08 volumio volumio[1143]: info: MRS: Getting audio outputs on start Oct 23 15:35:08 volumio volumio[1143]: info: MRS: Requesting all other devices output Oct 23 15:35:08 volumio volumio[1143]: info: MRS: Adding multiroomSync output Oct 23 15:35:08 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:08 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 15:35:08 volumio volumio[1143]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 23 15:35:08 volumio volumio[1143]: info: Received Get System Version Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 15:35:08 volumio volumio[1143]: info: Received Get System Info Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 15:35:08 volumio volumio[1143]: info: Discovery: Getting this device information Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:08 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 15:35:08 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 23 15:35:08 volumio volumio[1143]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 23 15:35:08 volumio volumio[1143]: info: MPD Permissions set Oct 23 15:35:08 volumio sudo[2570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 23 15:35:08 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:08 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 23 15:35:08 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:09 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 23 15:35:09 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:09 volumio volumiobt[2577]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 23 15:35:09 volumio sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 23 15:35:09 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:09 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:09 volumio sudo[2580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 23 15:35:09 volumio sudo[2580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:09 volumio sudo[2580]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:09 volumio volumiobt[2582]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 23 15:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:35:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:35:09 volumio bluetoothd[714]: Adv Monitor app :1.32 disconnected from D-Bus Oct 23 15:35:09 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 23 15:35:09 volumio volumiobt[2585]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 23 15:35:09 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:09 volumio autossh[2587]: port set to 0, monitoring disabled Oct 23 15:35:09 volumio autossh[2587]: starting ssh (count 1) Oct 23 15:35:09 volumio autossh[2587]: ssh child pid is 2591 Oct 23 15:35:09 volumio volumiobt[2588]: [176B blob data] Oct 23 15:35:09 volumio volumiobt[2588]: [157B blob data] Oct 23 15:35:09 volumio volumiobt[2588]: [157B blob data] Oct 23 15:35:09 volumio volumiobt[2588]: [157B blob data] Oct 23 15:35:09 volumio volumio[1143]: info: Executing endpoint qc_getconfig Oct 23 15:35:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 23 15:35:09 volumio volumiobt[2588]: [113B blob data] Oct 23 15:35:09 volumio volumiobt[2588]: [bluetoothctl]> discoverable on Oct 23 15:35:09 volumio volumiobt[2588]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 23 15:35:09 volumio volumiobt[2588]: [bluetoothctl]> pairable on Oct 23 15:35:09 volumio bluetoothd[714]: Path / reserved for Adv Monitor app :1.33 Oct 23 15:35:09 volumio bluetoothd[714]: Adv Monitor app :1.33 disconnected from D-Bus Oct 23 15:35:09 volumio volumiobt[2588]: [bluetoothctl]> Oct 23 15:35:09 volumio volumiobt[2593]: INFO [BTSTART] Registering Bluetooth agent... Oct 23 15:35:09 volumio volumiobt[2594]: [NEW] Media /org/bluez/hci0 Oct 23 15:35:09 volumio volumiossh-tunnel[2591]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 23 15:35:09 volumio volumiobt[2594]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio volumiobt[2594]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio volumiobt[2594]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio bluetoothd[714]: Adv Monitor app :1.34 disconnected from D-Bus Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.327 [2568.2568] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 23 15:35:09 volumio volumiobt[2597]: No agent is registered Oct 23 15:35:09 volumio volumiobt[2597]: [NEW] Media /org/bluez/hci0 Oct 23 15:35:09 volumio volumiobt[2597]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio volumiobt[2597]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio volumiobt[2597]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 23 15:35:09 volumio bluetoothd[714]: Adv Monitor app :1.35 disconnected from D-Bus Oct 23 15:35:09 volumio volumiobt[2598]: INFO [BTSTART] Agent registered successfully. Oct 23 15:35:09 volumio volumiobt[2599]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 23 15:35:09 volumio volumio[1143]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 23 15:35:09 volumio volumio[1143]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 23 15:35:09 volumio volumio[1143]: info: Remote SSH Started Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.429 [2568.2568] INFO VolumeManager: [0xe7d5c8]: Setting new playback volume: 75 Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.430 [2568.2568] INFO VolumeManager: [0xe7d5c8]: Setting new mute state: 0 Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.430 [2568.2568] INFO QobuzConnect: [0xe7df98]: Client initialized! Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.430 [2568.2568] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.452 [2568.2568] INFO LocalConfigManager: [0xe7cea8]: Starting Local Configuration server Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.452 [2568.2568] INFO SampleApp: Starting Local configuration server Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.452 [2568.2568] INFO SampleApp: Connected to UNIX socket client 0xe67818 Oct 23 15:35:09 volumio qobuz-connect[2568]: 20251023 15:35:09.477 [2568.2568] INFO SampleApp: Playback volume changed: 75 Oct 23 15:35:09 volumio volumio[1143]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 23 15:35:09 volumio volumio[1143]: info: CoreCommandRouter::volumioStop Oct 23 15:35:09 volumio volumio[1143]: info: CoreStateMachine::stop Oct 23 15:35:09 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 15:35:09 volumio volumio[1143]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 23 15:35:09 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumiobt[2600]: INFO:a2dp-agent:Connecting to system bus. Oct 23 15:35:10 volumio volumiobt[2600]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Oct 23 15:35:10 volumio volumiobt[2600]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Oct 23 15:35:10 volumio volumiobt[2600]: INFO:a2dp-agent:Registering agent. Oct 23 15:35:10 volumio volumiobt[2600]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Oct 23 15:35:10 volumio volumiobt[2600]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:10 volumio volumio[1143]: error: updateQueue error: null Oct 23 15:35:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:10 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 23 15:35:10 volumio volumio[1143]: info: Starting Shairport Sync Oct 23 15:35:10 volumio sudo[2612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 15:35:10 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 23 15:35:10 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Oct 23 15:35:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 23 15:35:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:10 volumio systemd[1]: shairport-sync.service: Consumed 1.557s CPU time. Oct 23 15:35:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:10 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:10 volumio volumio[1143]: info: MRS: Found cast device: Chromecast-Ultra-b8d5e9e179c52d9e75eab34ae52a3903 Oct 23 15:35:10 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:11 volumio volumio[1143]: info: Shairport-Sync Started Oct 23 15:35:11 volumio volumio[1143]: info: TidalConnect service stoped! Oct 23 15:35:11 volumio volumio[1143]: info: MRS: Found cast device: Chromecast-5702c0fa26409e4dc282cbccce3735c4 Oct 23 15:35:11 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:11 volumio volumio[1143]: info: MRS: Found cast device: Google-Nest-Hub-da739cebc7bb692ba8bab67636d07159 Oct 23 15:35:11 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:11 volumio volumio[1143]: info: Asound.conf file unchanged, so no further update is needed Oct 23 15:35:11 volumio volumio[1143]: info: Output device has changed, restarting MPD Oct 23 15:35:11 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Oct 23 15:35:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:11 volumio sudo[2643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 15:35:11 volumio sudo[2641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 15:35:11 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:11 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:11 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 23 15:35:12 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 15:35:12 volumio volumio[1143]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 15:35:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:12 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 23 15:35:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 23 15:35:12 volumio systemd[1]: mpd.service: Consumed 1.372s CPU time. Oct 23 15:35:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 23 15:35:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 23 15:35:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 23 15:35:12 volumio volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:35:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:12 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Oct 23 15:35:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 23 15:35:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 23 15:35:12 volumio sudo[2653]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 23 15:35:12 volumio sudo[2653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 23 15:35:12 volumio sudo[2653]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:12 volumio sudo[2661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 23 15:35:12 volumio sudo[2661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:12 volumio sudo[2661]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:12 volumio volumio[1143]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:35:12 volumio volumio[1143]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:35:12 volumio sudo[2663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 23 15:35:12 volumio volumio[1143]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 23 15:35:12 volumio volumio[1143]: info: Reading ALSA contributions from plugins. Oct 23 15:35:12 volumio sudo[2663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:12 volumio volumio[1143]: info: MPD Permissions set Oct 23 15:35:12 volumio sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 23 15:35:12 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:12 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 23 15:35:12 volumio qobuz-connect[2568]: 20251023 15:35:12.903 [2568.2568] INFO SampleApp: Stopping Local configuration server Oct 23 15:35:12 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 23 15:35:12 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 23 15:35:12 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:13 volumio volumio[1143]: info: MRS: Found cast device: Chromecast-Ultra-bb5475a65116e0c6bfcdcd498eee7139 Oct 23 15:35:13 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:13 volumio volumio[1143]: info: MRS: Found cast device: Pixel-Tablet-0ec232000af4d4d244b16df9ac1f5c64 Oct 23 15:35:13 volumio volumio[1143]: info: Adding audio output: Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 23 15:35:13 volumio sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:13 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 23 15:35:13 volumio sudo[2669]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:13 volumio qobuz-connect[2568]: 20251023 15:35:13.469 [2568.2568] INFO SampleApp: shat down connection on UNIX socket Oct 23 15:35:13 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 23 15:35:13 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:35:13 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:35:13 volumio sudo[2663]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:13 volumio volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:35:13 volumio volumio[1143]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 23 15:35:13 volumio volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:13 volumio volumio[1143]: info: Starting Shairport Sync Oct 23 15:35:13 volumio volumio[1143]: info: Executing endpoint qc_getconfig Oct 23 15:35:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 23 15:35:13 volumio sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 15:35:13 volumio sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.955 [2679.2679] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.960 [2679.2679] INFO VolumeManager: [0x1faa5c8]: Setting new playback volume: 75 Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.973 [2679.2679] INFO VolumeManager: [0x1faa5c8]: Setting new mute state: 0 Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.973 [2679.2679] INFO QobuzConnect: [0x1faaf98]: Client initialized! Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.973 [2679.2679] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 23 15:35:13 volumio mpd[2658]: 2025-10-23T15:35:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.992 [2679.2679] INFO LocalConfigManager: [0x1fa9ea8]: Starting Local Configuration server Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.993 [2679.2679] INFO SampleApp: Starting Local configuration server Oct 23 15:35:13 volumio qobuz-connect[2679]: 20251023 15:35:13.993 [2679.2679] INFO SampleApp: Connected to UNIX socket client 0x1f94818 Oct 23 15:35:13 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 23 15:35:13 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:14 volumio volumio[1143]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 23 15:35:14 volumio volumio[1143]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 23 15:35:14 volumio volumio[1143]: info: Executing endpoint tc_getconfig Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 23 15:35:14 volumio vtcs[2677]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 23 15:35:14 volumio vtcs[2677]: STARTED TidalConnect services. Oct 23 15:35:14 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:14 volumio mpd_monitor.sh[2691]: MPD Monitor Service: Starting MPD Monitor Service Oct 23 15:35:14 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 23 15:35:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 23 15:35:14 volumio qobuz-connect[2679]: 20251023 15:35:14.106 [2679.2679] INFO SampleApp: Playback volume changed: 75 Oct 23 15:35:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:14 volumio systemd[1]: shairport-sync.service: Consumed 1.260s CPU time. Oct 23 15:35:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:14 volumio sudo[2683]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:14 volumio volumio[1143]: info: Executing endpoint tc_connect Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 23 15:35:14 volumio volumio[1143]: info: Connecting to TidalConnect Oct 23 15:35:14 volumio volumio[1143]: info: Successfully started MPD Monitor Oct 23 15:35:14 volumio volumio[1143]: info: Asound.conf file written Oct 23 15:35:14 volumio sudo[2718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 23 15:35:14 volumio sudo[2718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:14 volumio sudo[2718]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:14 volumio volumio[1143]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 23 15:35:14 volumio volumio[1143]: No state is present for card b1 Oct 23 15:35:14 volumio volumio[1143]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 23 15:35:14 volumio volumio[1143]: /usr/share/alsa/init/bcm2835:14: control element not found Oct 23 15:35:14 volumio volumio[1143]: No state is present for card b1 Oct 23 15:35:14 volumio volumio[1143]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 23 15:35:14 volumio volumio[1143]: No state is present for card Headphones Oct 23 15:35:14 volumio volumio[1143]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 23 15:35:14 volumio volumio[1143]: /usr/share/alsa/init/bcm2835:20: control element not found Oct 23 15:35:14 volumio volumio[1143]: No state is present for card Headphones Oct 23 15:35:14 volumio volumio[1143]: info: Output device has changed, restarting MPD Oct 23 15:35:14 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:14 volumio sudo[2739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 15:35:14 volumio sudo[2739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:14 volumio sudo[2739]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:14 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 15:35:14 volumio volumio[1143]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:14 volumio sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 15:35:14 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:14 volumio volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:35:14 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:14 volumio vtcs[2677]: [2025-10-23 15:35:14.747] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 23 15:35:14 volumio vtcs[2677]: [2025-10-23 15:35:14.748] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 23 15:35:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 23 15:35:14 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 23 15:35:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 23 15:35:14 volumio systemd[1]: mpd.service: Consumed 1.475s CPU time. Oct 23 15:35:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 23 15:35:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 23 15:35:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 23 15:35:14 volumio sudo[2751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 15:35:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 23 15:35:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 23 15:35:15 volumio sudo[2751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:15 volumio sudo[2753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 15:35:15 volumio sudo[2753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:15 volumio sudo[2759]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 23 15:35:15 volumio sudo[2759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 23 15:35:15 volumio sudo[2759]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:15 volumio volumio[1143]: error: updateQueue error: null Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:15 volumio sudo[2765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 23 15:35:15 volumio sudo[2765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:15 volumio volumio[1143]: info: Shairport-Sync Started Oct 23 15:35:15 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 23 15:35:15 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 23 15:35:15 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 23 15:35:15 volumio volumio[1143]: info: MPD Permissions set Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio sudo[2753]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio sudo[2751]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:35:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:35:15 volumio sudo[2765]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:15 volumio volumio[1143]: info: Starting Shairport Sync Oct 23 15:35:15 volumio sudo[2774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 23 15:35:15 volumio sudo[2774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:15 volumio sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 15:35:15 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 23 15:35:15 volumio qobuz-connect[2679]: 20251023 15:35:15.771 [2679.2679] INFO SampleApp: Stopping Local configuration server Oct 23 15:35:15 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 23 15:35:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 23 15:35:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:15 volumio systemd[1]: shairport-sync.service: Consumed 1.333s CPU time. Oct 23 15:35:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:35:15 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:15 volumio volumio[1143]: info: Shairport-Sync Started Oct 23 15:35:16 volumio qobuz-connect[2679]: 20251023 15:35:16.006 [2679.2679] INFO SampleApp: shat down connection on UNIX socket Oct 23 15:35:16 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 23 15:35:16 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:35:16 volumio volumio[1143]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 23 15:35:16 volumio volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:35:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:16 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:35:16 volumio sudo[2774]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:16 volumio volumio[1143]: info: Executing endpoint qc_getconfig Oct 23 15:35:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.163 [2790.2790] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.167 [2790.2790] INFO VolumeManager: [0x1c6f5c8]: Setting new playback volume: 75 Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.167 [2790.2790] INFO VolumeManager: [0x1c6f5c8]: Setting new mute state: 0 Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.167 [2790.2790] INFO QobuzConnect: [0x1c6ff98]: Client initialized! Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.168 [2790.2790] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 23 15:35:16 volumio volumio[1143]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 23 15:35:16 volumio volumio[1143]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.186 [2790.2790] INFO LocalConfigManager: [0x1c6eea8]: Starting Local Configuration server Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.186 [2790.2790] INFO SampleApp: Starting Local configuration server Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.186 [2790.2790] INFO SampleApp: Connected to UNIX socket client 0x1c59818 Oct 23 15:35:16 volumio qobuz-connect[2790]: 20251023 15:35:16.314 [2790.2790] INFO SampleApp: Playback volume changed: 75 Oct 23 15:35:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:16 volumio volumio[1143]: info: TidalConnect service started! Oct 23 15:35:16 volumio mpd[2764]: 2025-10-23T15:35:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 23 15:35:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 23 15:35:16 volumio sudo[2741]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:16 volumio volumio[1143]: error: updateQueue error: null Oct 23 15:35:17 volumio sudo[2803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 23 15:35:17 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 23 15:35:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 15:35:17 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 23 15:35:17 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 23 15:35:17 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:17 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:17 volumio mpd_monitor.sh[2805]: MPD Monitor Service: Starting MPD Monitor Service Oct 23 15:35:17 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:17 volumio volumio[1143]: info: Successfully started MPD Monitor Oct 23 15:35:17 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:35:17 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:35:17 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:35:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 23 15:35:17 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:35:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:35:18 volumio volumio[1143]: info: TidalConnect service stoped! Oct 23 15:35:18 volumio volumio[1143]: info: TidalConnect service stoped! Oct 23 15:35:18 volumio volumio[1143]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:35:18 volumio volumio[1143]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:35:18 volumio sudo[2819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 23 15:35:18 volumio sudo[2819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:18 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 23 15:35:18 volumio sudo[2819]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:18 volumio volumio[1143]: info: Executing endpoint tc_getconfig Oct 23 15:35:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 23 15:35:18 volumio vtcs[2822]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 23 15:35:18 volumio vtcs[2822]: STARTED TidalConnect services. Oct 23 15:35:19 volumio volumio[1143]: info: Executing endpoint tc_connect Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 23 15:35:19 volumio volumio[1143]: info: Connecting to TidalConnect Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 23 15:35:19 volumio volumio[1143]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 23 15:35:19 volumio volumio[1143]: verbose: CURRENT POSITION 0 Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: info: No code Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 23 15:35:19 volumio volumio[1143]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 23 15:35:19 volumio volumio[1143]: verbose: CURRENT POSITION 0 Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: info: No code Oct 23 15:35:19 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:35:19 volumio volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:35:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:19 volumio volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:35:19 volumio volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:35:19 volumio volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:35:19 volumio volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:35:19 volumio sudo[2841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 23 15:35:19 volumio sudo[2841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:19 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 23 15:35:20 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 23 15:35:20 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 23 15:35:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 15:35:20 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 23 15:35:20 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 23 15:35:20 volumio sudo[2841]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:20 volumio mpd_monitor.sh[2843]: MPD Monitor Service: Starting MPD Monitor Service Oct 23 15:35:20 volumio volumio[1143]: info: Successfully started MPD Monitor Oct 23 15:35:20 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:35:20 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:35:20 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:35:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 23 15:35:20 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:35:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:35:21 volumio sudo[2851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 15:35:21 volumio sudo[2851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:21 volumio sudo[2851]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:21 volumio sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 15:35:21 volumio sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:21 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:21 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.30.206 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 23 15:35:21 volumio volumio[1143]: info: Received Get System Info Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 15:35:21 volumio volumio[1143]: info: Discovery: Getting this device information Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 15:35:21 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:35:21 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:35:21 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:35:21 volumio volumio[1143]: info: Retrieving Cloud Streaming UI Oct 23 15:35:21 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 23 15:35:21 volumio volumio[1143]: info: Getting Tidal Cloud Configuration Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:35:21 volumio volumio[1143]: info: Getting Qobuz Cloud Configuration Oct 23 15:35:21 volumio volumio[1143]: info: Asking plugin for UI Config Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:35:21 volumio volumio[1143]: info: Getting Spotify Cloud Configuration Oct 23 15:35:21 volumio volumio[1143]: info: Asking plugin for UI Config Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:35:21 volumio volumio[1143]: info: Saving Spotify Acccount Oct 23 15:35:21 volumio volumio[1143]: info: Got it Oct 23 15:35:21 volumio volumio[1143]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 15:35:21 volumio volumio[1143]: info: TidalConnect service started! Oct 23 15:35:21 volumio volumio[1143]: info: Got Tidal Cloud Configuration Oct 23 15:35:21 volumio volumio[1143]: info: Got it Oct 23 15:35:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:35:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 23 15:35:22 volumio volumio[1143]: info: Loading i18n strings for locale en Oct 23 15:35:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 23 15:35:22 volumio volumio[1143]: info: Setting timezone to Europe/London Oct 23 15:35:22 volumio sudo[2865]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 23 15:35:22 volumio sudo[2865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:22 volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:23 volumio sudo[2869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Oct 23 15:35:23 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:23 volumio sudo[2869]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:23 volumio sudo[2873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 23 15:35:23 volumio sudo[2873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:23 volumio sudo[2873]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:23 volumio sudo[2877]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Oct 23 15:35:23 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:23 volumio dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.53' (uid=0 pid=2878 comm="/usr/bin/timedatectl set-timezone Europe/London") Oct 23 15:35:23 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 23 15:35:23 volumio dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 23 15:35:23 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 23 15:35:23 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:23 volumio volumio[1143]: info: Fetching Streaming Services browse cache Oct 23 15:35:23 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:35:23 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 23 15:35:23 volumio volumio[1143]: info: Loading i18n strings for locale en Oct 23 15:35:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 23 15:35:24 volumio volumio[1143]: info: Setting timezone to Europe/London Oct 23 15:35:24 volumio sudo[2882]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 23 15:35:24 volumio sudo[2882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:24 volumio sudo[2882]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:24 volumio sudo[2886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Oct 23 15:35:24 volumio sudo[2886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:24 volumio sudo[2886]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:24 volumio sudo[2890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 23 15:35:24 volumio sudo[2890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:24 volumio sudo[2890]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:24 volumio sudo[2902]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Oct 23 15:35:24 volumio sudo[2902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:24 volumio sudo[2902]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 23 15:35:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 23 15:35:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:35:24 volumio sudo[2911]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 23 15:35:24 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:24 volumio volumio[1143]: info: Fetching Streaming Services browse cache Oct 23 15:35:25 volumio volumio[1143]: Updating browse sources language Oct 23 15:35:25 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:35:25 volumio volumio[1143]: Updating browse sources language Oct 23 15:35:25 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:35:26 volumio sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 15:35:26 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:26 volumio sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 15:35:26 volumio sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:35:26 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:26 volumio sudo[2917]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:26 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.30.206 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 23 15:35:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 15:35:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:35:27 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 15:35:39 volumio volumio[1143]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 23 15:35:39 volumio volumio[1143]: info: Received Get System Version Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 15:35:39 volumio volumio[1143]: info: Received Get System Info Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 15:35:39 volumio volumio[1143]: info: Discovery: Getting this device information Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:35:39 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 15:35:53 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 23 15:35:53 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 23 15:35:54 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:35:54 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:35:54 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 23 15:35:54 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:35:54 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:36:02 volumio sudo[2976]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 15:36:02 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:02 volumio sudo[2977]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 15:36:02 volumio sudo[2977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:02 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:02 volumio sudo[2977]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:02 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.30.206 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 23 15:36:02 volumio volumio[1143]: info: Received Get System Info Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 15:36:02 volumio volumio[1143]: info: Discovery: Getting this device information Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 15:36:02 volumio volumio-remote-updater[743]: Test mode disabled Oct 23 15:36:02 volumio volumio-remote-updater[743]: Alpha mode disabled Oct 23 15:36:02 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:02 volumio volumio[1143]: info: Retrieving Cloud Streaming UI Oct 23 15:36:02 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:36:02 volumio volumio[1143]: info: Getting Tidal Cloud Configuration Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:02 volumio volumio[1143]: info: Getting Qobuz Cloud Configuration Oct 23 15:36:02 volumio volumio[1143]: info: Asking plugin for UI Config Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:02 volumio volumio[1143]: info: Getting Spotify Cloud Configuration Oct 23 15:36:02 volumio volumio[1143]: info: Asking plugin for UI Config Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:02 volumio volumio[1143]: info: Saving Spotify Acccount Oct 23 15:36:02 volumio volumio[1143]: info: Got it Oct 23 15:36:02 volumio volumio[1143]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 23 15:36:02 volumio volumio[1143]: info: Got Tidal Cloud Configuration Oct 23 15:36:02 volumio volumio[1143]: info: Got it Oct 23 15:36:02 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:03 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 23 15:36:03 volumio volumio[1143]: info: Loading i18n strings for locale en Oct 23 15:36:03 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 23 15:36:03 volumio volumio[1143]: info: Setting timezone to Europe/London Oct 23 15:36:03 volumio sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 23 15:36:04 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:04 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:04 volumio sudo[2991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Oct 23 15:36:04 volumio sudo[2991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:04 volumio sudo[2991]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:04 volumio sudo[2995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 23 15:36:04 volumio sudo[2995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:04 volumio sudo[2995]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:04 volumio sudo[2999]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Oct 23 15:36:04 volumio sudo[2999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:04 volumio dbus-daemon[716]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.56' (uid=0 pid=3000 comm="/usr/bin/timedatectl set-timezone Europe/London") Oct 23 15:36:04 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 23 15:36:04 volumio dbus-daemon[716]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 23 15:36:04 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 23 15:36:04 volumio sudo[2999]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:04 volumio volumio[1143]: info: Fetching Streaming Services browse cache Oct 23 15:36:04 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 23 15:36:04 volumio volumio[1143]: info: MyVolumio login type: Token Oct 23 15:36:05 volumio volumio[1143]: Updating browse sources language Oct 23 15:36:05 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 15:36:07 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:07 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:14 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 23 15:36:16 volumio volumio[1143]: info: System name has changed, restarting Shairport Sync Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:16 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 15:36:16 volumio volumio[1143]: info: Updating MyVolumio device info Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:16 volumio volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:36:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:16 volumio vtcs[2822]: [2025-10-23 15:36:16.830] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 23 15:36:16 volumio vtcs[2822]: [2025-10-23 15:36:16.830] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 23 15:36:16 volumio volumio[1143]: info: Discovery: Restarting Advertising due to device name change Oct 23 15:36:16 volumio sudo[3038]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 23 15:36:16 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:16 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Oct 23 15:36:16 volumio systemd[1]: upmpdcli.service: Consumed 25.475s CPU time. Oct 23 15:36:17 volumio sudo[3039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 15:36:17 volumio sudo[3039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 volumio sudo[3042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 15:36:17 volumio sudo[3042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 23 15:36:17 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 23 15:36:17 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 23 15:36:17 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 23 15:36:17 volumio sudo[3049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 15:36:17 volumio sudo[3039]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 volumio sudo[3042]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 23 15:36:17 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio sudo[3059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 23 15:36:17 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 volumio volumio[1143]: error: Failed to update MyVolumio device: {"message":"Authentication token does not match provided user id"} Oct 23 15:36:17 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 23 15:36:17 volumio qobuz-connect[2790]: 20251023 15:36:17.490 [2790.2790] INFO SampleApp: Stopping Local configuration server Oct 23 15:36:17 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 volumio volumio[1143]: info: Permissions for /etc/hosts set Oct 23 15:36:17 volumio volumio[1143]: info: Starting Shairport Sync Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 volumio sudo[3063]: volumio : unable to resolve host volumio: System error Oct 23 15:36:17 volumio sudo[3063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname pi4aud Oct 23 15:36:17 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 pi4aud sudo[3063]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 pi4aud sudo[3065]: volumio : unable to resolve host volumio: System error Oct 23 15:36:17 pi4aud sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 15:36:17 pi4aud sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 pi4aud volumio[1143]: info: New hostname set Oct 23 15:36:17 pi4aud volumio[1143]: info: Hostname now is pi4aud Oct 23 15:36:17 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:17 pi4aud systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 23 15:36:17 pi4aud systemd[1]: shairport-sync.service: Deactivated successfully. Oct 23 15:36:17 pi4aud systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:36:17 pi4aud systemd[1]: shairport-sync.service: Consumed 1.447s CPU time. Oct 23 15:36:17 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:17 pi4aud sudo[3069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Oct 23 15:36:17 pi4aud sudo[3069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:17 pi4aud systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 23 15:36:17 pi4aud sudo[3065]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:17 pi4aud sudo[3069]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: Permissions for /etc/avahi/services/volumio.service Oct 23 15:36:18 pi4aud volumio[1143]: info: Shairport-Sync Started Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud volumio[1143]: info: Discovery: A device disappeared from network Oct 23 15:36:18 pi4aud volumio[1143]: info: Discovery: Device volumio disappeared from network Oct 23 15:36:18 pi4aud volumio[1143]: info: Discovery: A device disappeared from network Oct 23 15:36:18 pi4aud qobuz-connect[2790]: 20251023 15:36:18.258 [2790.2790] INFO SampleApp: shat down connection on UNIX socket Oct 23 15:36:18 pi4aud systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 23 15:36:18 pi4aud systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:36:18 pi4aud avahi-daemon[712]: Files changed, reloading. Oct 23 15:36:18 pi4aud avahi-daemon[712]: Loading service file /services/volumio.service. Oct 23 15:36:18 pi4aud systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 23 15:36:18 pi4aud sudo[3057]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:18 pi4aud volumio[1143]: info: Avahi name changed to pi4aud Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 23 15:36:18 pi4aud volumio[1143]: info: QobuzConnect: setDeactiveState invoked Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: Executing endpoint qc_getconfig Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.673 [3086.3086] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.677 [3086.3086] INFO VolumeManager: [0x69c5c8]: Setting new playback volume: 75 Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.677 [3086.3086] INFO VolumeManager: [0x69c5c8]: Setting new mute state: 0 Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.677 [3086.3086] INFO QobuzConnect: [0x69cf98]: Client initialized! Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.677 [3086.3086] INFO SampleApp: Starting Avahi advertising, name: pi4aud, service name: _qobuz-connect._tcp Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.688 [3086.3086] INFO LocalConfigManager: [0x69bea8]: Starting Local Configuration server Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.688 [3086.3086] INFO SampleApp: Starting Local configuration server Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.688 [3086.3086] INFO SampleApp: Connected to UNIX socket client 0x686818 Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:18 pi4aud volumio[1143]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 23 15:36:18 pi4aud volumio[1143]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:18 pi4aud qobuz-connect[3086]: 20251023 15:36:18.822 [3086.3086] INFO SampleApp: Playback volume changed: 75 Oct 23 15:36:18 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:19 pi4aud avahi-daemon[712]: Service "pi4aud" (/services/volumio.service) successfully established. Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:19 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: TidalConnect service stoped! Oct 23 15:36:20 pi4aud volumio[1143]: info: TidalConnect service stoped! Oct 23 15:36:20 pi4aud volumio[1143]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:36:20 pi4aud volumio[1143]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud sudo[3104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 23 15:36:20 pi4aud sudo[3104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud sudo[3104]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:20 pi4aud volumio[1143]: info: Executing endpoint tc_getconfig Oct 23 15:36:20 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 23 15:36:20 pi4aud vtcs[3107]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 23 15:36:20 pi4aud vtcs[3107]: STARTED TidalConnect services. Oct 23 15:36:20 pi4aud volumio[1143]: info: Executing endpoint tc_connect Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 23 15:36:21 pi4aud volumio[1143]: info: Connecting to TidalConnect Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::servicePushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 23 15:36:21 pi4aud volumio[1143]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 23 15:36:21 pi4aud volumio[1143]: verbose: CURRENT POSITION 0 Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::syncState stateService stop Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: info: No code Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::servicePushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 23 15:36:21 pi4aud volumio[1143]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 23 15:36:21 pi4aud volumio[1143]: verbose: CURRENT POSITION 0 Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::syncState stateService stop Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: info: No code Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:21 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YrxTMrZO0hhgstHugheAGhS2zD02.13c9326e718d80be43472e5c80f805c0.state.status' Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:21 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:21 pi4aud volumio[1143]: info: Discovery: Started advertising with name: pi4aud Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:22 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:23 pi4aud volumio[1143]: info: Discovery: adding e1ece3ee-3487-43fb-9c06-42a6b8ff0555 Oct 23 15:36:23 pi4aud volumio[1143]: info: Discovery: Found device pi4aud Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:23 pi4aud volumio[1143]: info: Discovery: this is already registered, e1ece3ee-3487-43fb-9c06-42a6b8ff0555 Oct 23 15:36:23 pi4aud volumio[1143]: info: Discovery: Found device pi4aud Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:23 pi4aud volumio[1143]: verbose: New Socket.io Connection to 192.168.30.206:3000 from 192.168.30.46 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:23 pi4aud volumio[1143]: info: TidalConnect service started! Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:23 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:24 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:25 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:26 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud sudo[3144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Oct 23 15:36:27 pi4aud sudo[3145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud sudo[3145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud sudo[3144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud sudo[3145]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud volumio[1143]: info: Internal perms successfully set Oct 23 15:36:27 pi4aud sudo[3144]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud volumio[1143]: info: smb.conf Permissions set Oct 23 15:36:27 pi4aud sudo[3149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Oct 23 15:36:27 pi4aud sudo[3149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud nmbd[1042]: [2025/10/23 15:36:27.222369, 0] ../../source3/nmbd/nmbd.c:59(terminate) Oct 23 15:36:27 pi4aud nmbd[1042]: Got SIGTERM: going down... Oct 23 15:36:27 pi4aud systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Oct 23 15:36:27 pi4aud systemd[1]: nmbd.service: Deactivated successfully. Oct 23 15:36:27 pi4aud systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Oct 23 15:36:27 pi4aud systemd[1]: Starting nmbd.service - Samba NMB Daemon... Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud sudo[3154]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 15:36:27 pi4aud sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud sudo[3156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 15:36:27 pi4aud sudo[3156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud sudo[3154]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud sudo[3156]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 23 15:36:27 pi4aud sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 23 15:36:27 pi4aud sudo[3161]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud volumio[1143]: info: Upmpdcli Daemon Started Oct 23 15:36:27 pi4aud nmbd[3164]: [2025/10/23 15:36:27.729013, 0] ../../source3/nmbd/nmbd.c:901(main) Oct 23 15:36:27 pi4aud nmbd[3164]: nmbd version 4.17.8-Raspbian started. Oct 23 15:36:27 pi4aud nmbd[3164]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 23 15:36:27 pi4aud nmbd[3164]: [2025/10/23 15:36:27.732466, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 23 15:36:27 pi4aud nmbd[3164]: started asyncdns process 3169 Oct 23 15:36:27 pi4aud systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 23 15:36:27 pi4aud sudo[3149]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:27 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:27 pi4aud upmpdcli[3182]: writing RSA key Oct 23 15:36:27 pi4aud sudo[3180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Oct 23 15:36:27 pi4aud sudo[3180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:27 pi4aud systemd[1]: Stopping smbd.service - Samba SMB Daemon... Oct 23 15:36:27 pi4aud systemd[1]: smbd.service: Deactivated successfully. Oct 23 15:36:27 pi4aud systemd[1]: Stopped smbd.service - Samba SMB Daemon. Oct 23 15:36:27 pi4aud systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:28 pi4aud smbd[3193]: [2025/10/23 15:36:28.254307, 0] ../../source3/smbd/server.c:1741(main) Oct 23 15:36:28 pi4aud smbd[3193]: smbd version 4.17.8-Raspbian started. Oct 23 15:36:28 pi4aud smbd[3193]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 23 15:36:28 pi4aud systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 23 15:36:28 pi4aud sudo[3180]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:28 pi4aud volumio[1143]: info: SAMBA Restarted Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:28 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:29 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:30 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 23 15:36:31 pi4aud volumio[1143]: info: Preparing to save Alsa Options, stopping services first Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:31 pi4aud volumio[1143]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"audiophonics-es9028q2m-dac","label":"Audiophonics I-Sabre ES9028Q2M"},"output_device":{"value":1,"label":"Audiophonics I-Sabre ES9028Q2M"},"disallowPush":true} Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:31 pi4aud volumio[1143]: info: Enabling I2S DAC: Audiophonics I-Sabre ES9028Q2M Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 23 15:36:31 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:31 pi4aud sudo[3200]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 23 15:36:31 pi4aud sudo[3200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:32 pi4aud sudo[3200]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:32 pi4aud volumio[1143]: info: No Overlays Loaded Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:32 pi4aud sudo[3203]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i-sabre-q2m Oct 23 15:36:32 pi4aud sudo[3203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:32 pi4aud volumio[1143]: warn: Unable to locate the device Audiophonics I-Sabre ES9028Q2M a reboot may be required. Oct 23 15:36:32 pi4aud kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Oct 23 15:36:32 pi4aud kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Oct 23 15:36:32 pi4aud kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Oct 23 15:36:32 pi4aud kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Oct 23 15:36:32 pi4aud kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Oct 23 15:36:32 pi4aud sudo[3203]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:32 pi4aud kernel: snd-rpi-i-sabre-q2m soc:sound: snd_soc_register_card() failed: -517 Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:32 pi4aud volumio[1143]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M Oct 23 15:36:32 pi4aud kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics Device ID : 01 Oct 23 15:36:32 pi4aud kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics API revision : 02 Oct 23 15:36:32 pi4aud (udev-worker)[3226]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 23 15:36:32 pi4aud volumio[1143]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 23 15:36:32 pi4aud volumio[1143]: info: Disabling external Volume Control Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 23 15:36:32 pi4aud volumio[1143]: info: Preparing to generate the ALSA configuration file Oct 23 15:36:32 pi4aud sudo[3243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 23 15:36:32 pi4aud sudo[3243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:32 pi4aud sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 23 15:36:32 pi4aud sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:32 pi4aud sudo[3241]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:32 pi4aud volumio[1143]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 23 15:36:32 pi4aud volumio[1143]: info: Reading ALSA contributions from plugins. Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:32 pi4aud volumio[1143]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 23 15:36:32 pi4aud volumio[1143]: wlan0 Interface doesn't support scanning : Device or resource busy Oct 23 15:36:32 pi4aud volumio[1143]: info: Cannot use regular scanning, forcing with ap-force Oct 23 15:36:32 pi4aud sudo[3252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 23 15:36:32 pi4aud sudo[3252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:36:32 pi4aud sudo[3252]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:32 pi4aud volumio[1143]: command failed: Device or resource busy (-16) Oct 23 15:36:32 pi4aud volumio[1143]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 23 15:36:32 pi4aud volumio[1143]: command failed: Device or resource busy (-16) Oct 23 15:36:33 pi4aud volumio[1143]: info: I2S Param [object Object] successfully enabled Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: VolumeController:: Volume=100 Mute =false Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreStateMachine::pushState Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 23 15:36:33 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output update for this device Oct 23 15:36:33 pi4aud volumio[1143]: info: MRS: Pushing multiroomSync output Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 15:36:33 pi4aud volumio[1143]: info: Getting Alsa Cards List without I2S DAC Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 23 15:36:33 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 15:36:34 pi4aud volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 15:36:34 pi4aud volumio[1143]: [2025-10-23T14:36:34.177Z] @firebase/database: FIREBASE WARNING: set at /user_devices/YrxTMrZO0hhgstHugheAGhS2zD02/13c9326e718d80be43472e5c80f805c0/state failed: permission_denied Oct 23 15:36:34 pi4aud volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 15:36:34 pi4aud volumio[1143]: Error: PERMISSION_DENIED: Permission denied Oct 23 15:36:34 pi4aud volumio[1143]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12880:33 Oct 23 15:36:34 pi4aud volumio[1143]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Oct 23 15:36:34 pi4aud volumio[1143]: at Repo.callOnCompleteCallback (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12870:13) Oct 23 15:36:34 pi4aud volumio[1143]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12683:19 Oct 23 15:36:34 pi4aud volumio[1143]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11900:17 Oct 23 15:36:34 pi4aud volumio[1143]: at PersistentConnection.onDataMessage_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11930:17) Oct 23 15:36:34 pi4aud volumio[1143]: at Connection.onDataMessage_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11247:14) Oct 23 15:36:34 pi4aud volumio[1143]: at Connection.onPrimaryMessageReceived_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11241:18) Oct 23 15:36:34 pi4aud volumio[1143]: at WebSocketConnection.onMessage (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11142:27) Oct 23 15:36:34 pi4aud volumio[1143]: at WebSocketConnection.appendFrame_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10728:18) Oct 23 15:36:34 pi4aud volumio[1143]: at WebSocketConnection.handleIncomingFrame (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10779:22) Oct 23 15:36:34 pi4aud volumio[1143]: at mySock.onmessage (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10674:19) Oct 23 15:36:34 pi4aud volumio[1143]: at Client.dispatchEvent (/volumio/node_modules/faye-websocket/lib/faye/websocket/api/event_target.js:24:30) Oct 23 15:36:34 pi4aud volumio[1143]: at Client._receiveMessage (/volumio/node_modules/faye-websocket/lib/faye/websocket/api.js:152:10) Oct 23 15:36:34 pi4aud volumio[1143]: at Client. (/volumio/node_modules/faye-websocket/lib/faye/websocket/api.js:36:49) Oct 23 15:36:34 pi4aud volumio[1143]: at Client.emit (node:events:526:35) { Oct 23 15:36:34 pi4aud volumio[1143]: code: 'PERMISSION_DENIED' Oct 23 15:36:34 pi4aud volumio[1143]: } Oct 23 15:36:34 pi4aud volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 15:36:34 pi4aud systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 23 15:36:35 pi4aud sudo[3243]: pam_unix(sudo:session): session closed for user root Oct 23 15:36:37 pi4aud sudo[3298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-23 15:35' Oct 23 15:36:37 pi4aud sudo[3298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"