Mar 28 20:23:10 volumio volumio5-onboarding[1654]: time=2026-03-28T19:23:10.809Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.178.177:60936 error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 28 20:23:10 volumio volumio5-onboarding[1654]: time=2026-03-28T19:23:10.809Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.177:60936 Mar 28 20:23:10 volumio volumio5-onboarding[1654]: time=2026-03-28T19:23:10.809Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.177:60936 Mar 28 20:23:10 volumio dbus-daemon[870]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.14" (uid=0 pid=1654 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=869 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 28 20:23:19 volumio nmbd[1098]: [2026/03/28 20:23:19.442173, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 20:23:19 volumio nmbd[1098]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.123 for name WORKGROUP<1d>. Mar 28 20:23:19 volumio nmbd[1098]: This response was from IP 192.168.178.52, reporting an IP address of 192.168.178.52. Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 28 20:23:33 volumio volumio[1115]: info: System name has changed, restarting Shairport Sync Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 20:23:33 volumio volumio[1115]: info: Updating MyVolumio device info Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: QobuzConnect: setDeactiveState invoked Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:33 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:33 volumio vtcs[4109]: [2026-03-28 20:23:33.664] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 28 20:23:33 volumio vtcs[4109]: [2026-03-28 20:23:33.665] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 28 20:23:33 volumio sudo[6800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 20:23:33 volumio volumio[1115]: info: Discovery: Restarting Advertising Mar 28 20:23:33 volumio volumio[1115]: info: Discovery: Stopping existing advertisement Mar 28 20:23:33 volumio sudo[6800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio sudo[6802]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 28 20:23:33 volumio sudo[6802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 28 20:23:33 volumio sudo[6806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 20:23:33 volumio sudo[6802]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 volumio systemd[1]: vtcs.service: Killing process 4115 (vtcs) with signal SIGKILL. Mar 28 20:23:33 volumio sudo[6806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 28 20:23:33 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 28 20:23:33 volumio sudo[6800]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 volumio sudo[6813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 20:23:33 volumio sudo[6813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 28 20:23:33 volumio sudo[6806]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 20:23:33 volumio sudo[6813]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 volumio volumio[1115]: info: Received Get System Info Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:23:33 volumio volumio[1115]: info: Discovery: Getting this device information Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:33 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:23:33 volumio volumio[1115]: info: Received Get System Info Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 20:23:33 volumio volumio[1115]: info: Discovery: Getting this device information Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:33 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 20:23:33 volumio volumio5-onboarding[1654]: time=2026-03-28T19:23:33.743Z level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" name=MacMini7 Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 20:23:33 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 20:23:33 volumio volumio5-onboarding[1654]: time=2026-03-28T19:23:33.749Z level=INFO msg="device name changed, resetting service group" component=discovery/localnet Mar 28 20:23:33 volumio sudo[6820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 20:23:33 volumio sudo[6820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio volumio[1115]: info: Starting Shairport Sync Mar 28 20:23:33 volumio sudo[6822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Mar 28 20:23:33 volumio sudo[6822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 28 20:23:33 volumio qobuz-connect[4091]: 20260328 20:23:33.770 [4091.4091] INFO SampleApp: Stopping Local configuration server Mar 28 20:23:33 volumio sudo[6822]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 volumio volumio[1115]: info: Permissions for /etc/hosts set Mar 28 20:23:33 volumio sudo[6826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 20:23:33 volumio sudo[6826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 volumio volumio[1115]: info: Hostname now is macmini7 Mar 28 20:23:33 volumio sudo[6829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname macmini7 Mar 28 20:23:33 volumio sudo[6829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 macmini7 sudo[6829]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 macmini7 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 20:23:33 macmini7 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 20:23:33 macmini7 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 20:23:33 macmini7 systemd[1]: shairport-sync.service: Consumed 5.125s CPU time. Mar 28 20:23:33 macmini7 volumio[1115]: info: New hostname set Mar 28 20:23:33 macmini7 sudo[6832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Mar 28 20:23:33 macmini7 sudo[6832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:33 macmini7 sudo[6832]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 macmini7 volumio[1115]: info: Permissions for /etc/avahi/services/volumio.service Mar 28 20:23:33 macmini7 avahi-daemon[868]: Files changed, reloading. Mar 28 20:23:33 macmini7 volumio[1115]: info: Avahi name changed to macmini7 Mar 28 20:23:33 macmini7 avahi-daemon[868]: Loading service file /services/volumio.service. Mar 28 20:23:33 macmini7 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 20:23:33 macmini7 sudo[6826]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:33 macmini7 volumio[1115]: info: Shairport-Sync Started Mar 28 20:23:34 macmini7 volumio[1115]: error: Failed to update MyVolumio device: {"message":"Authentication token does not match provided user id"} Mar 28 20:23:34 macmini7 qobuz-connect[4091]: 20260328 20:23:34.167 [4091.4091] INFO SampleApp: shat down connection on UNIX socket Mar 28 20:23:34 macmini7 volumio[1115]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 28 20:23:34 macmini7 volumio[1115]: info: QobuzConnect: setDeactiveState invoked Mar 28 20:23:34 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:34 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:34 macmini7 systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 28 20:23:34 macmini7 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 20:23:34 macmini7 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 20:23:34 macmini7 sudo[6820]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:34 macmini7 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 20:23:34 macmini7 volumio[1115]: info: Executing endpoint qc_getconfig Mar 28 20:23:34 macmini7 volumio[1115]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.236 [6850.6850] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.238 [6850.6850] INFO VolumeManager: [0x55b3a994eb10]: Setting new playback volume: 75 Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.238 [6850.6850] INFO VolumeManager: [0x55b3a994eb10]: Setting new mute state: 0 Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.238 [6850.6850] INFO AudioStreamManager: [0x55b3a994e670]: Setting new audio download buffer size: 1048576 Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.238 [6850.6850] INFO QobuzConnect: [0x55b3a994fb20]: Client initialized! Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.238 [6850.6850] INFO SampleApp: Starting Avahi advertising, name: MacMini7, service name: _qobuz-connect._tcp Mar 28 20:23:34 macmini7 volumio[1115]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 28 20:23:34 macmini7 volumio[1115]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.246 [6850.6850] INFO LocalConfigManager: [0x55b3a994e150]: Starting Local Configuration server Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.246 [6850.6850] INFO SampleApp: Starting Local configuration server Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.246 [6850.6850] INFO SampleApp: Connected to UNIX socket client 0x55b3a9924bb0 Mar 28 20:23:34 macmini7 qobuz-connect[6850]: 20260328 20:23:34.387 [6850.6850] INFO SampleApp: Playback volume changed: 75 Mar 28 20:23:34 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:34 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:34 macmini7 systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 28 20:23:34 macmini7 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 28 20:23:34 macmini7 volumio[1115]: info: Discovery: A device disappeared from network Mar 28 20:23:34 macmini7 volumio[1115]: info: Discovery: A device disappeared from network Mar 28 20:23:34 macmini7 volumio[1115]: info: Discovery: A device disappeared from network Mar 28 20:23:34 macmini7 volumio[1115]: info: Discovery: A device disappeared from network Mar 28 20:23:34 macmini7 avahi-daemon[868]: Service "MacMini7" (/services/volumio.service) successfully established. Mar 28 20:23:35 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:35.668Z level=INFO msg="service successfully established" component=discovery/localnet Mar 28 20:23:36 macmini7 volumio[1115]: info: TidalConnect service stoped! Mar 28 20:23:36 macmini7 volumio[1115]: info: TidalConnect service stoped! Mar 28 20:23:36 macmini7 volumio[1115]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 20:23:36 macmini7 volumio[1115]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 20:23:36 macmini7 sudo[6895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 20:23:36 macmini7 sudo[6895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 20:23:36 macmini7 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 28 20:23:36 macmini7 sudo[6895]: pam_unix(sudo:session): session closed for user root Mar 28 20:23:36 macmini7 volumio[1115]: info: Executing endpoint tc_getconfig Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 28 20:23:36 macmini7 vtcs[6898]: STARTING TidalConnect services, version: 1.6.1 Mar 28 20:23:36 macmini7 vtcs[6898]: STARTED TidalConnect services. Mar 28 20:23:36 macmini7 volumio[1115]: info: Executing endpoint tc_connect Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 28 20:23:36 macmini7 volumio[1115]: info: Connecting to TidalConnect Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::servicePushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: 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} Mar 28 20:23:36 macmini7 volumio[1115]: verbose: CURRENT POSITION 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::syncState stateService stop Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: No code Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::servicePushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: 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} Mar 28 20:23:36 macmini7 volumio[1115]: verbose: CURRENT POSITION 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::syncState stateService stop Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: No code Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreStateMachine::pushState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioPushState Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output update for this device Mar 28 20:23:36 macmini7 volumio[1115]: info: MRS: Pushing multiroomSync output Mar 28 20:23:36 macmini7 volumio[1115]: info: CoreCommandRouter::volumioGetState Mar 28 20:23:36 macmini7 volumio[1115]: info: CorePlayQueue::getTrack 0 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.859Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.860Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.860Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.861Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.862Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:36 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:36.862Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0xc000355230" state=STATUS_STOPPED positionMs=0 volume=42 Mar 28 20:23:37 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:37.050Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Mar 28 20:23:37 macmini7 volumio5-onboarding[1654]: time=2026-03-28T19:23:37.050Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0xc000355230" event=SERVER_EVENT_TYPE_DEVICE_NAME_CHANGED error="peer is gone" Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.543Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.544Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.544Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.545Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.545Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: [2026-03-28T19:23:37.545Z] @firebase/database: FIREBASE WARNING: set at /user_devices/eNQuhxtJVfMdZHVFb3PZzIVTiuh1/150b60109a0919a5f2fa6b4a651e73f7/state failed: permission_denied Mar 28 20:23:37 macmini7 volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:23:37 macmini7 volumio[1115]: Error: PERMISSION_DENIED: Permission denied Mar 28 20:23:37 macmini7 volumio[1115]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12880:33 Mar 28 20:23:37 macmini7 volumio[1115]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Mar 28 20:23:37 macmini7 volumio[1115]: at Repo.callOnCompleteCallback (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12870:13) Mar 28 20:23:37 macmini7 volumio[1115]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:12683:19 Mar 28 20:23:37 macmini7 volumio[1115]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11900:17 Mar 28 20:23:37 macmini7 volumio[1115]: at PersistentConnection.onDataMessage_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11930:17) Mar 28 20:23:37 macmini7 volumio[1115]: at Connection.onDataMessage_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11247:14) Mar 28 20:23:37 macmini7 volumio[1115]: at Connection.onPrimaryMessageReceived_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11241:18) Mar 28 20:23:37 macmini7 volumio[1115]: at WebSocketConnection.onMessage (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11142:27) Mar 28 20:23:37 macmini7 volumio[1115]: at WebSocketConnection.appendFrame_ (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10728:18) Mar 28 20:23:37 macmini7 volumio[1115]: at WebSocketConnection.handleIncomingFrame (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10779:22) Mar 28 20:23:37 macmini7 volumio[1115]: at mySock.onmessage (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10674:19) Mar 28 20:23:37 macmini7 volumio[1115]: at Client.dispatchEvent (/volumio/node_modules/faye-websocket/lib/faye/websocket/api/event_target.js:24:30) Mar 28 20:23:37 macmini7 volumio[1115]: at Client._receiveMessage (/volumio/node_modules/faye-websocket/lib/faye/websocket/api.js:152:10) Mar 28 20:23:37 macmini7 volumio[1115]: at Client. (/volumio/node_modules/faye-websocket/lib/faye/websocket/api.js:36:49) Mar 28 20:23:37 macmini7 volumio[1115]: at Client.emit (node:events:526:35) { Mar 28 20:23:37 macmini7 volumio[1115]: code: 'PERMISSION_DENIED' Mar 28 20:23:37 macmini7 volumio[1115]: } Mar 28 20:23:37 macmini7 volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:23:37 macmini7 sudo[6929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 20:22' Mar 28 20:23:37 macmini7 sudo[6929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"