Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 04 19:50:00 loe volumio[13512]: info: Loading i18n strings for locale nl Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 04 19:50:00 loe volumio[13512]: info: Setting timezone to Europe/Amsterdam Mar 04 19:50:00 loe sudo[15734]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 04 19:50:00 loe sudo[15734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15734]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime Mar 04 19:50:00 loe sudo[15738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15738]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 04 19:50:00 loe sudo[15742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15742]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15746]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam Mar 04 19:50:00 loe sudo[15746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15746]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 04 19:50:00 loe sudo[15749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 04 19:50:00 loe sudo[15749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe volumio[13512]: Updating browse sources language Mar 04 19:50:00 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:00 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:50:00 loe volumio[13512]: info: Fetching Streaming Services browse cache Mar 04 19:50:01 loe sudo[15749]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:01 loe volumio5-onboarding[2437]: time=2026-03-04T19:50:01.876+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:50:01 loe volumio[13512]: info: Received Get System Info Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:50:01 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 19:50:14 loe volumio[13512]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 19:50:14 loe volumio[13512]: info: Received Get System Version Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:50:14 loe volumio[13512]: info: Received Get System Info Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:50:14 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 04 19:50:19 loe volumio[13512]: info: System name has changed, restarting Shairport Sync Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:19 loe volumio[13512]: info: Updating MyVolumio device info Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:19 loe vtcs[15318]: [2026-03-04 19:50:19.190] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 04 19:50:19 loe vtcs[15318]: [2026-03-04 19:50:19.191] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 04 19:50:19 loe volumio[13512]: info: Discovery: Restarting Advertising Mar 04 19:50:19 loe volumio[13512]: info: Discovery: Stopping existing advertisement Mar 04 19:50:19 loe sudo[15785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:19 loe sudo[15785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15787]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 04 19:50:19 loe sudo[15787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 04 19:50:19 loe systemd[1]: vtcs.service: Killing process 15332 (vtcs) with signal SIGKILL. Mar 04 19:50:19 loe sudo[15791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:19 loe sudo[15791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15787]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:19 loe systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 04 19:50:19 loe sudo[15798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 04 19:50:19 loe sudo[15798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15785]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:19 loe volumio[13512]: info: Starting Shairport Sync Mar 04 19:50:19 loe sudo[15798]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15791]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:19 loe sudo[15807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Mar 04 19:50:19 loe sudo[15805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15805]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 04 19:50:19 loe sudo[15808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe volumio[13512]: info: Permissions for /etc/hosts set Mar 04 19:50:19 loe volumio[13512]: info: Hostname now is volumio Mar 04 19:50:19 loe systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 04 19:50:19 loe systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 19:50:19 loe qobuz-connect[15262]: 20260304 19:50:19.721 [15262.15262] INFO SampleApp: Stopping Local configuration server Mar 04 19:50:19 loe systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 19:50:19 loe systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:19 loe systemd[1]: shairport-sync.service: Consumed 2.707s CPU time. Mar 04 19:50:19 loe sudo[15814]: volumio : unable to resolve host loe: System error Mar 04 19:50:19 loe sudo[15814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio Mar 04 19:50:19 loe sudo[15814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 volumio sudo[15814]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio volumio[13512]: info: New hostname set Mar 04 19:50:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:19 volumio sudo[15807]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio sudo[15816]: volumio : unable to resolve host loe: System error Mar 04 19:50:19 volumio volumio[13512]: info: Shairport-Sync Started Mar 04 19:50:19 volumio sudo[15816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Mar 04 19:50:19 volumio sudo[15816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 volumio sudo[15816]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio volumio[13512]: info: Permissions for /etc/avahi/services/volumio.service Mar 04 19:50:19 volumio avahi-daemon[695]: Files changed, reloading. Mar 04 19:50:19 volumio volumio[13512]: info: Avahi name changed to volumio Mar 04 19:50:19 volumio avahi-daemon[695]: Service group file /services/volumio.service changed, reloading. Mar 04 19:50:20 volumio volumio[13512]: info: Successfully Updated MyVolumio device Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: A device disappeared from network Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: Device loe disappeared from network Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: A device disappeared from network Mar 04 19:50:20 volumio avahi-daemon[695]: Service "Volumio" (/services/volumio.service) successfully established. Mar 04 19:50:20 volumio qobuz-connect[15262]: 20260304 19:50:20.819 [15262.15262] INFO SampleApp: shat down connection on UNIX socket Mar 04 19:50:20 volumio volumio[13512]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 04 19:50:20 volumio volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:20 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:20 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 04 19:50:20 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:20 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:20 volumio sudo[15808]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:21 volumio volumio[13512]: info: Executing endpoint qc_getconfig Mar 04 19:50:21 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.055 [15834.15834] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 04 19:50:21 volumio volumio[13512]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 04 19:50:21 volumio volumio[13512]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO VolumeManager: [0x200408]: Setting new playback volume: 75 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO VolumeManager: [0x200408]: Setting new mute state: 0 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO QobuzConnect: [0x200dd8]: Client initialized! Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.113 [15834.15834] INFO LocalConfigManager: [0x1ffce8]: Starting Local Configuration server Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.113 [15834.15834] INFO SampleApp: Starting Local configuration server Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.114 [15834.15834] INFO SampleApp: Connected to UNIX socket client 0x1ea818 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.205 [15834.15834] INFO SampleApp: Playback volume changed: 75 Mar 04 19:50:21 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 04 19:50:22 volumio volumio[13512]: info: Preparing to save Alsa Options, stopping services first Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio[13512]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry DAC Plus"},"output_device":{"value":1,"label":"HiFiBerry DAC Plus"},"disallowPush":true} Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 04 19:50:22 volumio volumio[13512]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 04 19:50:22 volumio volumio[13512]: info: Disabling external Volume Control Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Preparing to generate the ALSA configuration file Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Retrieving Cloud Streaming UI Mar 04 19:50:22 volumio volumio[13512]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 04 19:50:22 volumio volumio[13512]: info: Reading ALSA contributions from plugins. Mar 04 19:50:22 volumio volumio[13512]: info: TidalConnect service stoped! Mar 04 19:50:22 volumio volumio[13512]: info: TidalConnect service stoped! Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: Getting Alsa Cards List without I2S DAC Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 19:50:22 volumio volumio[13512]: info: Getting Tidal Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Getting Qobuz Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: Asking plugin for UI Config Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Getting Spotify Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: Asking plugin for UI Config Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Saving Spotify Acccount Mar 04 19:50:22 volumio volumio[13512]: info: Got it Mar 04 19:50:22 volumio volumio[13512]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 04 19:50:22 volumio volumio[13512]: info: VolumeController:: Volume=5 Mute =false Mar 04 19:50:22 volumio volumio[13512]: info: CoreStateMachine::pushState Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:22 volumio volumio[13512]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:50:22 volumio volumio[13512]: info: MRS: Pushing multiroomSync output Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.915+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" state=STATUS_UNSPECIFIED positionMs= volume=5 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.918+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" id= title= Mar 04 19:50:22 volumio volumio[13512]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:50:22 volumio volumio[13512]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.mjORZRjMx8YIlyGajvB2CXSYA5r2.a53384b16fd4d7a180fdac1b5068092c.state.status' Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.966+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.147:52756 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.966+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.147:52756 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.973+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.147:39918 Mar 04 19:50:23 volumio volumio[13512]: info: Asound.conf file unchanged, so no further update is needed Mar 04 19:50:23 volumio volumio[13512]: info: Output device has changed, restarting MPD Mar 04 19:50:23 volumio volumio[13512]: info: Output device has changed, restarting Shairport Sync Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:23 volumio sudo[15889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 19:50:23 volumio sudo[15889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 19:50:23 volumio sudo[15891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15889]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:23 volumio volumio[13512]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:23 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 19:50:23 volumio sudo[15901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:23 volumio sudo[15901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:23 volumio sudo[15903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio volumio[13512]: info: Got Tidal Cloud Configuration Mar 04 19:50:23 volumio volumio[13512]: info: Got it Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:23 volumio volumio[13512]: info: MPD Permissions set Mar 04 19:50:23 volumio volumio[13512]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 04 19:50:23 volumio volumio[13512]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 04 19:50:23 volumio sudo[15911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 19:50:23 volumio sudo[15911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 19:50:23 volumio systemd[1]: mpd.service: Consumed 6.134s CPU time. Mar 04 19:50:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 19:50:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 19:50:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 19:50:23 volumio volumio[13512]: info: Starting Shairport Sync Mar 04 19:50:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 19:50:23 volumio sudo[15920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 04 19:50:23 volumio sudo[15920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:23 volumio sudo[15924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15911]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio sudo[15901]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:50:23 volumio sudo[15903]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 04 19:50:23 volumio volumio[13512]: Job for vtcs.service canceled. Mar 04 19:50:23 volumio volumio[13512]: at ChildProcess.exithandler (node:child_process:421:12) Mar 04 19:50:23 volumio volumio[13512]: at ChildProcess.emit (node:events:514:28) Mar 04 19:50:23 volumio volumio[13512]: at maybeClose (node:internal/child_process:1105:16) Mar 04 19:50:23 volumio volumio[13512]: at Socket. (node:internal/child_process:457:11) Mar 04 19:50:23 volumio volumio[13512]: at Socket.emit (node:events:514:28) Mar 04 19:50:23 volumio volumio[13512]: at Pipe. (node:net:337:12) { Mar 04 19:50:23 volumio volumio[13512]: code: 1, Mar 04 19:50:23 volumio volumio[13512]: killed: false, Mar 04 19:50:23 volumio volumio[13512]: signal: null, Mar 04 19:50:23 volumio volumio[13512]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Mar 04 19:50:23 volumio volumio[13512]: stdout: '', Mar 04 19:50:23 volumio volumio[13512]: stderr: 'Job for vtcs.service canceled.\n' Mar 04 19:50:23 volumio volumio[13512]: } Mar 04 19:50:23 volumio volumio[13512]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:50:23 volumio sudo[15926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 04 19:50:23 volumio sudo[15926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 19:50:23 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:23 volumio sudo[15920]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 19:50:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:23 volumio systemd[1]: shairport-sync.service: Consumed 1.690s CPU time. Mar 04 19:50:23 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 04 19:50:23 volumio qobuz-connect[15834]: 20260304 19:50:23.880 [15834.15834] INFO SampleApp: Stopping Local configuration server Mar 04 19:50:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:23 volumio sudo[15924]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio sudo[15921]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 19:50:23 volumio sudo[15921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 19:50:23 volumio sudo[15921]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:25 volumio qobuz-connect[15834]: 20260304 19:50:25.122 [15834.15834] INFO SampleApp: shat down connection on UNIX socket Mar 04 19:50:25 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 04 19:50:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:25 volumio sudo[15926]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:25 volumio sudo[15963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 19:49' Mar 04 19:50:25 volumio sudo[15963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:25 volumio sudo[15963]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:26 volumio volumio-remote-updater[708]: [2026-03-04 19:50:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 04 19:50:26 volumio volumio-remote-updater[708]: [2026-03-04 19:50:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 04 19:50:26 volumio go-librespot[13865]: time="2026-03-04T19:50:26+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 04 19:50:26 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:26.010+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 04 19:50:26 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:26.015+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:55978->127.0.0.1:3000: read: connection reset by peer" Mar 04 19:50:26 volumio vtcs[15940]: BYE TidalConnect Mar 04 19:50:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:26 volumio qobuz-connect[15960]: Error: Application ID was not specified! Mar 04 19:50:26 volumio qobuz-connect[15960]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:50:26 volumio qobuz-connect[15960]: -m|--manufacturer: Manufacturer of the device Mar 04 19:50:26 volumio qobuz-connect[15960]: -n|--deviceName: Device name Mar 04 19:50:26 volumio qobuz-connect[15960]: -l|--model: Device's model name Mar 04 19:50:26 volumio qobuz-connect[15960]: -s|--serialNumber: Device's serial number Mar 04 19:50:26 volumio qobuz-connect[15960]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:50:26 volumio qobuz-connect[15960]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:50:26 volumio qobuz-connect[15960]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:50:26 volumio qobuz-connect[15960]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:50:26 volumio qobuz-connect[15960]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:50:26 volumio qobuz-connect[15960]: -h|--help: Print this help Mar 04 19:50:26 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:26 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:50:26 volumio sudo[15891]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:26 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Mar 04 19:50:26 volumio systemd[1]: upmpdcli.service: Consumed 2min 56.319s CPU time. Mar 04 19:50:26 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 04 19:50:26 volumio systemd[1]: volumio.service: Consumed 2min 6.133s CPU time. Mar 04 19:50:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 04 19:50:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 04 19:50:26 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Mar 04 19:50:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Mar 04 19:50:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 04 19:50:26 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 04 19:50:26 volumio systemd[1]: volumio.service: Consumed 2min 6.133s CPU time. Mar 04 19:50:26 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Mar 04 19:50:26 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:26 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 04 19:50:26 volumio vtcs[15997]: BYE TidalConnect Mar 04 19:50:26 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:27 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:27.018+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 19:50:27 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Mar 04 19:50:27 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:27 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:27 volumio vtcs[16010]: BYE TidalConnect Mar 04 19:50:27 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:27 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Mar 04 19:50:27 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:27 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:28 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:28.022+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 19:50:28 volumio vtcs[16012]: BYE TidalConnect Mar 04 19:50:28 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:28 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Mar 04 19:50:28 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:28 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:29 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:29.027+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 19:50:29 volumio vtcs[16014]: BYE TidalConnect Mar 04 19:50:29 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:29 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Mar 04 19:50:29 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:29 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Mar 04 19:50:29 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Mar 04 19:50:29 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:30 volumio mpd[15944]: 2026-03-04T19:50:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 19:50:30 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 19:50:30 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:30 volumio volumio[15992]: info: ----- Volumio3 ---- Mar 04 19:50:30 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:30 volumio volumio[15992]: info: ----- System startup ---- Mar 04 19:50:30 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:30 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Mar 04 19:50:31 volumio volumio-remote-updater[708]: [2026-03-04 19:50:30] [connect] Successful connection Mar 04 19:50:31 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. Mar 04 19:50:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:31 volumio volumio[15992]: info: MYVOLUMIO Environment detected Mar 04 19:50:31 volumio volumio[15992]: info: Plugin folders cleanup Mar 04 19:50:31 volumio volumio[15992]: info: Scanning into folder /volumio/app/plugins/ Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category audio_interface Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category miscellanea Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category music_service Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category plugins.json Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category system_controller Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category user_interface Mar 04 19:50:31 volumio volumio[15992]: info: Scanning into folder /data/plugins/ Mar 04 19:50:31 volumio volumio[15992]: info: Scanning category music_service Mar 04 19:50:31 volumio volumio[15992]: info: Plugin folders cleanup completed Mar 04 19:50:31 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:31 volumio volumio[15992]: info: ----- Core plugins startup ---- Mar 04 19:50:31 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:31 volumio volumio[15992]: info: Loading plugins from folder /volumio/app/plugins/ Mar 04 19:50:31 volumio volumio[15992]: info: Adding plugin upnp to MyMusic Plugins Mar 04 19:50:31 volumio volumio[15992]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 04 19:50:31 volumio volumio[15992]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 04 19:50:31 volumio volumio[15992]: info: Loading plugins from folder /data/plugins/ Mar 04 19:50:31 volumio volumio[15992]: info: Loading plugin "system"... Mar 04 19:50:31 volumio volumio[15992]: info: Loading plugin "appearance"... Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "network"... Mar 04 19:50:33 volumio volumio[15992]: info: Refreshing Cached IP Addresses Mar 04 19:50:33 volumio sudo[16034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 19:50:33 volumio sudo[16034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:33 volumio sudo[16036]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 19:50:33 volumio sudo[16034]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:33 volumio sudo[16036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "services"... Mar 04 19:50:33 volumio sudo[16036]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "volumio5onboarding"... Mar 04 19:50:33 volumio sudo[16043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 04 19:50:33 volumio sudo[16043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "alsa_controller"... Mar 04 19:50:33 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "wizard"... Mar 04 19:50:33 volumio volumio[15992]: info: Loading plugin "networkfs"... Mar 04 19:50:33 volumio volumio[15992]: info: Starting Udev Watcher for removable devices Mar 04 19:50:34 volumio volumio[15992]: info: Ignoring mount for partition: boot Mar 04 19:50:34 volumio volumio[15992]: info: Ignoring mount for partition: volumio Mar 04 19:50:34 volumio volumio[15992]: info: Ignoring mount for partition: volumio_data Mar 04 19:50:34 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 19:50:34 volumio volumio[15992]: info: Loading plugin "volumio_command_line_client"... Mar 04 19:50:34 volumio volumio[15992]: info: Loading plugin "upnp"... Mar 04 19:50:34 volumio volumio[15992]: info: [1772650234039] Starting Upmpd Daemon Mar 04 19:50:34 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 19:50:34 volumio volumio[15992]: info: Loading plugin "my_music"... Mar 04 19:50:34 volumio volumio[15992]: info: Loading plugin "mpd"... Mar 04 19:50:34 volumio sudo[16043]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:34 volumio volumio[15992]: info: Loading plugin "upnp_browser"... Mar 04 19:50:34 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:34.864+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:39918 @ 0x279f4a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:50:37 volumio volumio[15992]: info: Starting UPNP Browser Mar 04 19:50:37 volumio volumio[15992]: info: Loading plugin "alarm-clock"... Mar 04 19:50:37 volumio volumio[15992]: info: Loading plugin "airplay_emulation"... Mar 04 19:50:37 volumio volumio[15992]: info: Starting Shairport Sync Mar 04 19:50:37 volumio volumio[15992]: info: Loading plugin "last_100"... Mar 04 19:50:37 volumio volumio[15992]: info: Loading plugin "webradio"... Mar 04 19:50:38 volumio volumio[15992]: info: Loading plugin "i2s_dacs"... Mar 04 19:50:38 volumio volumio[15992]: info: Loading plugin "volumiodiscovery"... Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 19:50:38 volumio node[15992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** For more information see Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 19:50:38 volumio volumio[15992]: *** WARNING *** For more information see Mar 04 19:50:38 volumio node[15992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 19:50:38 volumio node[15992]: *** WARNING *** For more information see Mar 04 19:50:38 volumio node[15992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 04 19:50:38 volumio node[15992]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 19:50:38 volumio node[15992]: *** WARNING *** For more information see Mar 04 19:50:38 volumio volumio[15992]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 04 19:50:38 volumio volumio[15992]: info: Discovery: Started advertising with name: Volumio Mar 04 19:50:38 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 19:50:38 volumio volumio[15992]: info: Loading plugin "spop"... Mar 04 19:50:39 volumio volumio[15992]: info: Loading plugin "outputs"... Mar 04 19:50:40 volumio volumio[15992]: info: Loading plugin "albumart"... Mar 04 19:50:40 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:40.031+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:56044->127.0.0.1:3000: i/o timeout" Mar 04 19:50:40 volumio volumio[15992]: info: Plugin example_plugin is not enabled Mar 04 19:50:40 volumio volumio[15992]: info: Loading plugin "inputs"... Mar 04 19:50:40 volumio volumio[15992]: info: Loading plugin "updater_comm"... Mar 04 19:50:40 volumio volumio[15992]: info: Plugin mpdemulation is not enabled Mar 04 19:50:40 volumio volumio[15992]: info: Loading plugin "rest_api"... Mar 04 19:50:40 volumio volumio[15992]: info: Loading plugin "websocket"... Mar 04 19:50:40 volumio volumio[15992]: info: Starting Socket.io Server version 1.7.4 Mar 04 19:50:40 volumio volumio[15992]: info: Loading i18n strings for locale nl Mar 04 19:50:40 volumio volumio[15992]: Updating browse sources language Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::initPlayerControls Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:40 volumio volumio[15992]: Express server listening on port 3000 Mar 04 19:50:40 volumio volumio[15992]: [Metrics] WebUI: 11s 434.78ms Mar 04 19:50:40 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:40.696+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.147:39918 @ 0x279f4a0" latency=26.456173ms timeout=10s Mar 04 19:50:40 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:40.696+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.147:39918 @ 0x279f4a0" Mar 04 19:50:40 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:40.697+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.147:39918 @ 0x279f4a0" latency=29.5944ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 19:50:40 volumio volumio[15992]: info: CoreStateMachine::resetVolumioState Mar 04 19:50:40 volumio volumio[15992]: info: CoreStateMachine::getcurrentVolume Mar 04 19:50:40 volumio volumio[15992]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 19:50:40 volumio volumio[15992]: info: Volumio Network Manager: Network status updated: 1 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:41 volumio volumio[15992]: info: Reloading queue from file Mar 04 19:50:41 volumio volumio[16069]: Forking 3 albumart workers Mar 04 19:50:41 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Mar 04 19:50:41 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:50:41 volumio systemd[1]: upmpdcli.service: Consumed 2min 56.319s CPU time. Mar 04 19:50:41 volumio volumio[15992]: info: No valid Plugin REST Endpoint Mar 04 19:50:41 volumio qobuz-connect[16025]: 20260304 19:50:41.207 [16025.16025] ERROR SampleApp: api request was not successful Mar 04 19:50:41 volumio qobuz-connect[16025]: Error: Application ID was not specified! Mar 04 19:50:41 volumio qobuz-connect[16025]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:50:41 volumio qobuz-connect[16025]: -m|--manufacturer: Manufacturer of the device Mar 04 19:50:41 volumio qobuz-connect[16025]: -n|--deviceName: Device name Mar 04 19:50:41 volumio qobuz-connect[16025]: -l|--model: Device's model name Mar 04 19:50:41 volumio qobuz-connect[16025]: -s|--serialNumber: Device's serial number Mar 04 19:50:41 volumio qobuz-connect[16025]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:50:41 volumio qobuz-connect[16025]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:50:41 volumio qobuz-connect[16025]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:50:41 volumio qobuz-connect[16025]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:50:41 volumio qobuz-connect[16025]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:50:41 volumio qobuz-connect[16025]: -h|--help: Print this help Mar 04 19:50:41 volumio volumio[15992]: info: VolumeController:: Volume=5 Mute =false Mar 04 19:50:41 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:50:41 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:41 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::pushState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::updateTrackBlock Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrackBlock Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::setRepeat false single undefined Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::pushState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::setRandom undefined Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::pushState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:41 volumio volumio[15992]: info: Setting Device type: Raspberry PI Mar 04 19:50:41 volumio volumio[15992]: info: Completed loading Core Plugins Mar 04 19:50:41 volumio volumio[15992]: info: Preparing to generate the ALSA configuration file Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:50:41 volumio volumio[15992]: info: Discovery: Getting this device information Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:50:41 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 19:50:41 volumio volumio[15992]: verbose: New Socket.io Connection to 192.168.178.145:3000 from 192.168.178.147 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 19:50:41 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 04 19:50:41 volumio volumio[15992]: info: VolumeController:: Volume=5 Mute =false Mar 04 19:50:41 volumio volumio[15992]: info: CoreStateMachine::pushState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:41 volumio volumio[15992]: info: Discovery: adding 07fbb113-343c-4b34-af49-444dc3d8fb26 Mar 04 19:50:41 volumio volumio[15992]: info: Discovery: Found device Volumio Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: info: Discovery: this is already registered, 07fbb113-343c-4b34-af49-444dc3d8fb26 Mar 04 19:50:41 volumio volumio[15992]: info: Discovery: Found device Volumio Mar 04 19:50:41 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:41 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:41 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 19:50:41 volumio volumio[15992]: info: Asound.conf file written Mar 04 19:50:42 volumio upmpdcli[16140]: writing RSA key Mar 04 19:50:42 volumio sudo[16139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 04 19:50:42 volumio sudo[16139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio sudo[16139]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:42 volumio volumio[15992]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 04 19:50:42 volumio volumio[15992]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 04 19:50:42 volumio volumio[15992]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 19:50:42 volumio volumio[15992]: No state is present for card vc4hdmi Mar 04 19:50:42 volumio volumio[15992]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 19:50:42 volumio volumio[15992]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 04 19:50:42 volumio volumio[15992]: Hardware is initialized using a generic method Mar 04 19:50:42 volumio volumio[15992]: No state is present for card vc4hdmi Mar 04 19:50:42 volumio volumio[15992]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 04 19:50:42 volumio volumio[15992]: info: Output device has changed, restarting MPD Mar 04 19:50:42 volumio volumio[15992]: info: Output device has changed, restarting Shairport Sync Mar 04 19:50:42 volumio sudo[16148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:42 volumio sudo[16148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio sudo[16148]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:42 volumio sudo[16151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 19:50:42 volumio sudo[16151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio volumio[15992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:42 volumio volumio[15992]: info: ___________ START PLUGINS ___________ Mar 04 19:50:42 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 19:50:42 volumio volumio[15992]: info: ControllerMpd::onStart: Initializing MPD Mar 04 19:50:42 volumio volumio[15992]: info: Creating MPD Configuration file Mar 04 19:50:42 volumio sudo[16160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 04 19:50:42 volumio sudo[16160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 19:50:42 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 19:50:42 volumio systemd[1]: mpd.service: Consumed 6.529s CPU time. Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 19:50:42 volumio volumio[15992]: info: [1772650242639] CoreMusicLibrary::Adding element Media Servers Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:42 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 19:50:42 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 19:50:42 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 19:50:42 volumio volumio[15992]: info: UPNP Browser: Client initialized successfully Mar 04 19:50:42 volumio sudo[16164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 19:50:42 volumio sudo[16164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio sudo[16166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 19:50:42 volumio sudo[16166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:42 volumio sudo[16164]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:42 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 19:50:42 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 19:50:42 volumio sudo[16160]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:42 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 19:50:42 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 19:50:42 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 19:50:42 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 19:50:42 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 19:50:42 volumio volumio[15992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:42 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:43 volumio volumio[15992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 19:50:43 volumio volumio[15992]: info: [1772650243009] CoreMusicLibrary::Adding element Last_100 Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 19:50:43 volumio volumio[15992]: info: [1772650243033] CoreMusicLibrary::Adding element Webradio Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:50:43 volumio volumio[15992]: info: Initializing BBC Radios Mar 04 19:50:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:43 volumio volumio[15992]: info: Creating Spotify config file Mar 04 19:50:43 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:43 volumio volumio-remote-updater[708]: [2026-03-04 19:50:43] [connect] Successful connection Mar 04 19:50:43 volumio sudo[16180]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 19:50:43 volumio sudo[16180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 19:50:43 volumio sudo[16180]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:44 volumio volumio[16091]: Starting albumart workers Mar 04 19:50:44 volumio volumio[15992]: info: Volumio Calling Home Mar 04 19:50:44 volumio volumio[16092]: Starting albumart workers Mar 04 19:50:44 volumio volumio[16090]: Starting albumart workers Mar 04 19:50:44 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:44.863+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 19:50:44 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 19:50:45 volumio sudo[16217]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 19:50:45 volumio sudo[16217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:45 volumio sudo[16219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 19:50:45 volumio sudo[16219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:45 volumio sudo[16217]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:45 volumio sudo[16219]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:45 volumio volumio[15992]: info: MPD Permissions set Mar 04 19:50:45 volumio volumio[15992]: info: MPD Permissions set Mar 04 19:50:45 volumio volumio-remote-updater[708]: [2026-03-04 19:50:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772650243 101 Mar 04 19:50:45 volumio volumio[15992]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 19:50:45 volumio volumio[15992]: info: Volumio called home Mar 04 19:50:45 volumio volumio[15992]: info: Spotify config file written Mar 04 19:50:45 volumio sudo[16223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 04 19:50:45 volumio sudo[16223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:45 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 04 19:50:45 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 04 19:50:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:45 volumio go-librespot[16225]: go-librespot daemon starting... Mar 04 19:50:45 volumio sudo[16223]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:45 volumio go-librespot[16226]: time="2026-03-04T19:50:45+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:50:45 volumio go-librespot[16226]: time="2026-03-04T19:50:45+01:00" level=debug msg="app state loaded" Mar 04 19:50:45 volumio go-librespot[16226]: time="2026-03-04T19:50:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=info msg="zeroconf server listening on port 40407" Mar 04 19:50:46 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. Mar 04 19:50:46 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:46 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:46 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="obtained new client token: AADfvF9mICgVOrwdiHJzNdjIPJRIbFpHnJB1XSHeJn2IrSI2DDjNpSXdiYDtkvbJVPvumLxNF5tBScfTepiCUSwHd19w11a6XgJDBuso895JBaisp1VlRJ5D5K1+YjpzDnn+3aMT4GUwG/1FjMhWuvWP7dvoREcINy9eJL2bU9MT/nUMWM5jFI/8ohi191myrH5PTAsHFdWRzyuo5bjmEmi2D1yCWODiLDI0CiZmLBGua3G7FCJpAQn6lQ==" Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:46 volumio volumio[15992]: info: No need to fix Spotify hosts Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="completed keyexchange" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=debug msg="completed challenge" Mar 04 19:50:46 volumio go-librespot[16226]: time="2026-03-04T19:50:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:50:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:50:46 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 04 19:50:46 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 04 19:50:46 volumio volumio[15992]: info: An error occurred while refreshing Spotify Token Error: Bad Request Mar 04 19:50:46 volumio volumio[15992]: info: No valid Plugin REST Endpoint Mar 04 19:50:46 volumio qobuz-connect[16245]: 20260304 19:50:46.997 [16245.16245] ERROR SampleApp: api request was not successful Mar 04 19:50:46 volumio qobuz-connect[16245]: Error: Application ID was not specified! Mar 04 19:50:46 volumio qobuz-connect[16245]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:50:46 volumio qobuz-connect[16245]: -m|--manufacturer: Manufacturer of the device Mar 04 19:50:46 volumio qobuz-connect[16245]: -n|--deviceName: Device name Mar 04 19:50:46 volumio qobuz-connect[16245]: -l|--model: Device's model name Mar 04 19:50:46 volumio qobuz-connect[16245]: -s|--serialNumber: Device's serial number Mar 04 19:50:46 volumio qobuz-connect[16245]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:50:46 volumio qobuz-connect[16245]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:50:46 volumio qobuz-connect[16245]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:50:46 volumio qobuz-connect[16245]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:50:46 volumio qobuz-connect[16245]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:50:46 volumio qobuz-connect[16245]: -h|--help: Print this help Mar 04 19:50:47 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:47 volumio volumio[15992]: info: Starting Shairport Sync Mar 04 19:50:47 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:50:47 volumio volumio[15992]: info: Starting Shairport Sync Mar 04 19:50:47 volumio volumio[15992]: info: Starting Shairport Sync Mar 04 19:50:47 volumio sudo[16264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:47 volumio sudo[16264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:47 volumio sudo[16266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:47 volumio sudo[16266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:47 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 04 19:50:47 volumio sudo[16268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:47 volumio sudo[16268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:47 volumio volumio[15992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 04 19:50:47 volumio sudo[16271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 04 19:50:47 volumio sudo[16271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:47 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:47 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:50:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 19:50:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 19:50:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:47 volumio systemd[1]: shairport-sync.service: Consumed 2.054s CPU time. Mar 04 19:50:47 volumio sudo[16271]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:47 volumio volumio[15992]: info: Upmpdcli Daemon Started Mar 04 19:50:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:47 volumio sudo[16268]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:47 volumio sudo[16264]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:47 volumio sudo[16266]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:47 volumio volumio[15992]: info: Shairport-Sync Started Mar 04 19:50:47 volumio volumio[15992]: Error adding Membership: Error: addMembership EINVAL Mar 04 19:50:47 volumio volumio[15992]: info: Shairport-Sync Started Mar 04 19:50:47 volumio volumio[15992]: info: Shairport-Sync Started Mar 04 19:50:48 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:48.876+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.147:39918 Mar 04 19:50:48 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:48.876+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.147:39918 Mar 04 19:50:48 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:48.883+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.147:51934 Mar 04 19:50:49 volumio mpd[16195]: 2026-03-04T19:50:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 19:50:49 volumio volumio[15992]: info: go-librespot daemon successfully initialized Mar 04 19:50:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 04 19:50:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 19:50:49 volumio sudo[16151]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:49 volumio sudo[16166]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:49 volumio volumio[15992]: info: Completed starting Core Plugins Mar 04 19:50:49 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:49 volumio volumio[15992]: info: ----- MyVolumio plugins startup ---- Mar 04 19:50:49 volumio volumio[15992]: info: ------------------------------------------- Mar 04 19:50:49 volumio volumio[15992]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 04 19:50:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:49 volumio go-librespot[16294]: go-librespot daemon starting... Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="app state loaded" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:50:50 volumio volumio[15992]: error: MPD error: The expression evaluated to a falsy value: Mar 04 19:50:50 volumio volumio[15992]: assert.ok(self.idling) Mar 04 19:50:50 volumio volumio[15992]: error: The expression evaluated to a falsy value: Mar 04 19:50:50 volumio volumio[15992]: assert.ok(self.idling) Mar 04 19:50:50 volumio volumio[15992]: error: updateQueue error: null Mar 04 19:50:50 volumio volumio[15992]: info: MPD running with PID16195 Mar 04 19:50:50 volumio volumio[15992]: ,establishing connection Mar 04 19:50:50 volumio volumio[15992]: error: updateQueue error: null Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=info msg="zeroconf server listening on port 41731" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="obtained new client token: AABUEzJlcixHoN8k9CGkZN1tIwNnrP2BTxUfklny3WYehYkq/hqQxBgbeLr/PaYEl0CDn3Ip6uC8SS06LqXy2iRSAusqj+RHnm4hxXQghIgTw5wA0FDfI1PwDX/qdqlZAAS5VGDQxC0qbEZ81WGDgJOrDByJIPmKLiQq5Lu21LV13Y4+VtNbKD4lzW0AV77Mpu9tupjMEph5xT1ssNN0VkC3o4Axl+G/QrgeYg0LzMV6HejQj86MrVeXMQ==" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 04 19:50:50 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:50.697+01:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.147:39918 @ 0x279f4a0" latency=26.456173ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 19:50:50 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:50.697+01:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x279f4a0" id=348751428 status=STATUS_UNKNOWN error="no addresses to write to" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="completed keyexchange" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=debug msg="completed challenge" Mar 04 19:50:50 volumio go-librespot[16295]: time="2026-03-04T19:50:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:50:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:50:52 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. Mar 04 19:50:52 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:52 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:52 volumio volumio[15992]: info: No valid Plugin REST Endpoint Mar 04 19:50:52 volumio qobuz-connect[16302]: 20260304 19:50:52.273 [16302.16302] ERROR SampleApp: api request was not successful Mar 04 19:50:52 volumio qobuz-connect[16302]: Error: Application ID was not specified! Mar 04 19:50:52 volumio qobuz-connect[16302]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:50:52 volumio qobuz-connect[16302]: -m|--manufacturer: Manufacturer of the device Mar 04 19:50:52 volumio qobuz-connect[16302]: -n|--deviceName: Device name Mar 04 19:50:52 volumio qobuz-connect[16302]: -l|--model: Device's model name Mar 04 19:50:52 volumio qobuz-connect[16302]: -s|--serialNumber: Device's serial number Mar 04 19:50:52 volumio qobuz-connect[16302]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:50:52 volumio qobuz-connect[16302]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:50:52 volumio qobuz-connect[16302]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:50:52 volumio qobuz-connect[16302]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:50:52 volumio qobuz-connect[16302]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:50:52 volumio qobuz-connect[16302]: -h|--help: Print this help Mar 04 19:50:52 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:52 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:50:52 volumio volumio[15992]: info: Initializing connection to go-librespot Websocket Mar 04 19:50:52 volumio volumio[15992]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 19:50:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 04 19:50:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:53 volumio go-librespot[16304]: go-librespot daemon starting... Mar 04 19:50:53 volumio go-librespot[16305]: time="2026-03-04T19:50:53+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:50:53 volumio go-librespot[16305]: time="2026-03-04T19:50:53+01:00" level=debug msg="app state loaded" Mar 04 19:50:53 volumio go-librespot[16305]: time="2026-03-04T19:50:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=info msg="zeroconf server listening on port 40459" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="obtained new client token: AAASZ3VExJB4HeovuPSjh1tndGyDajJH05xsrQmHehb55LXOWjWaJEc327FQC7eR8NdrQX1hE8uny61I29W2Y99Cy5rdd1YIF1gQN2kg1sdK2KNUzcMWUQ1TPAps9VmvCyU8+7NqcjkiQzVIqVX92rQf3Se2aDZU7zL1e0lt/Z0yuqx6z0QwBRS6a9Jv7k5lfWjptZHyBPahmEYM5pfM+fU2rOEqliFJacPntlIft/arnHqJLYkgKxl90Q==" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="completed keyexchange" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=debug msg="completed challenge" Mar 04 19:50:54 volumio go-librespot[16305]: time="2026-03-04T19:50:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:50:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:50:54 volumio volumio[15992]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 04 19:50:55 volumio volumio[15992]: info: Initializing connection to go-librespot Websocket Mar 04 19:50:55 volumio volumio[15992]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 19:50:57 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Mar 04 19:50:57 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:57 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:57 volumio volumio[15992]: info: No valid Plugin REST Endpoint Mar 04 19:50:57 volumio qobuz-connect[16326]: 20260304 19:50:57.502 [16326.16326] ERROR SampleApp: api request was not successful Mar 04 19:50:57 volumio qobuz-connect[16326]: Error: Application ID was not specified! Mar 04 19:50:57 volumio qobuz-connect[16326]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:50:57 volumio qobuz-connect[16326]: -m|--manufacturer: Manufacturer of the device Mar 04 19:50:57 volumio qobuz-connect[16326]: -n|--deviceName: Device name Mar 04 19:50:57 volumio qobuz-connect[16326]: -l|--model: Device's model name Mar 04 19:50:57 volumio qobuz-connect[16326]: -s|--serialNumber: Device's serial number Mar 04 19:50:57 volumio qobuz-connect[16326]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:50:57 volumio qobuz-connect[16326]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:50:57 volumio qobuz-connect[16326]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:50:57 volumio qobuz-connect[16326]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:50:57 volumio qobuz-connect[16326]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:50:57 volumio qobuz-connect[16326]: -h|--help: Print this help Mar 04 19:50:57 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:57 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:50:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 04 19:50:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:50:57 volumio go-librespot[16327]: go-librespot daemon starting... Mar 04 19:50:57 volumio go-librespot[16328]: time="2026-03-04T19:50:57+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:50:57 volumio go-librespot[16328]: time="2026-03-04T19:50:57+01:00" level=debug msg="app state loaded" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin bluetooth to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin multiroom to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin metavolumio to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin cd_controller to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 04 19:50:58 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=info msg="zeroconf server listening on port 36461" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="obtained new client token: AACuqLaCWX2XXZVMeciiNwuY8hfVmUXOXLkm56NOKIwk+tHybKMu3DlrwYYrZaU6mYU5X9wFhUT+Bv+mzwAgGCpOy6PqQMvmpekBtJOM6Aa7ULC1CUMiBKVp7e3vZHsZ8fBqhCdZd1zab77Qrbb0gMdkl0Y2V3Uyzu25AJNHU483swAb7qhwZRZdNFRnqzHfQQUggDQ2sWaD1VgMmKbMwi2qRr2dalKrxZmrpbaqLfRi8WCjoy42cpmPyA==" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="completed keyexchange" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=debug msg="completed challenge" Mar 04 19:50:58 volumio go-librespot[16328]: time="2026-03-04T19:50:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:50:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:50:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:51:00 volumio volumio[15992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 04 19:51:00 volumio volumio[15992]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 04 19:51:00 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:00 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:00 volumio volumio[15992]: info: Starting MyVolumio Remote Streaming Endpoints Mar 04 19:51:00 volumio volumio[15992]: info: MyVolumio login type: Token Mar 04 19:51:00 volumio volumio[15992]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 04 19:51:00 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 04 19:51:01 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:01.574+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.147:51934 @ 0x24a7e00" latency=10.928168ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 19:51:01 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:01.576+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.147:51934 @ 0x24a7e00" latency=8.875247ms timeout=10s Mar 04 19:51:01 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:01.576+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" Mar 04 19:51:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 04 19:51:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:01 volumio volumio[15992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 04 19:51:01 volumio volumio[15992]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 04 19:51:01 volumio volumio[15992]: info: Streaming services startup Mar 04 19:51:01 volumio volumio[15992]: info: Starting Streaming Daemon Mar 04 19:51:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:01 volumio go-librespot[16335]: go-librespot daemon starting... Mar 04 19:51:02 volumio sudo[16337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 19:51:02 volumio sudo[16337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:02 volumio volumio[15992]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="app state loaded" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:51:02 volumio sudo[16337]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:02 volumio volumio[15992]: info: Initializing connection to go-librespot Websocket Mar 04 19:51:02 volumio volumio[15992]: info: Received Get System Info Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:51:02 volumio volumio[15992]: info: Discovery: Getting this device information Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:51:02 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.158+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" name=Volumio Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.160+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" language=nl Mar 04 19:51:02 volumio volumio[15992]: error: Cannot start Volumio Streaming Daemon Mar 04 19:51:02 volumio volumio[15992]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 04 19:51:02 volumio volumio[15992]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.209+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" timezone=Europe/Amsterdam Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.223+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" available=true connected=true macAddress=b8:27:eb:68:fe:6d ip4Address=192.168.178.145/24 ip6Address= Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="new websocket client" Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.228+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.229+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" setupComplete=true Mar 04 19:51:02 volumio volumio[15992]: info: Connection to go-librespot Websocket established Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:51:02 volumio volumio[15992]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:51:02 volumio volumio[15992]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:51:02 volumio volumio[15992]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 04 19:51:02 volumio volumio[15992]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:51:02 volumio volumio[15992]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=info msg="zeroconf server listening on port 33891" Mar 04 19:51:02 volumio volumio[15992]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:51:02 volumio volumio[15992]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:51:02 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Mar 04 19:51:02 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:51:02 volumio volumio[15992]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 04 19:51:02 volumio volumio[15992]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 19:51:02 volumio volumio[15992]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 04 19:51:02 volumio volumio[15992]: amixer -c 2 info | grep "vc4-hdmi" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="obtained new client token: AAADdSoGiFvpD0ix6bX2EBNSa6d+tHebER3Np/eWROKTSMQy0yQAuW3JhFndKuEk6H0py9VHwYmTdSebRR+huILJmSKfP8Tu3ryq0XD2X/O45eWCXVlVaxO0Kdm5yrVUf4c2MS0v0AwvG5FMh1PD++lOXQLKCS8NnQqTqCyMh0zB3/6Q1sGmeEZ1q/DKc9EoSxwRbT9PKjWA4WUpWCFX1reomGcn3+xO0/7RZjLdsYSiJI3R5KRIOkW7QA==" Mar 04 19:51:02 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:51:02 volumio volumio[15992]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.695+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" selectedOutputId=3 Mar 04 19:51:02 volumio volumio[15992]: info: Received Get System Info Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:51:02 volumio volumio[15992]: info: Discovery: Getting this device information Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:51:02 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.731+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" currentVersion=4.096 latestVersion=4.096 Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.731+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" status=UPDATE_STATUS_NONE progress=0 Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.732+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" userId= Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.732+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" providers=3 Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.733+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" plugins=62 Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 19:51:02 volumio volumio[15992]: info: CoreCommandRouter::volumioGetState Mar 04 19:51:02 volumio volumio[15992]: info: CorePlayQueue::getTrack 0 Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.751+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" state=STATUS_STOPPED positionMs=0 volume=5 Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.752+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:51934 @ 0x24a7e00" id=tidal://song/279755622 title="La Vida Breve: Danza Española Nr. 1" Mar 04 19:51:02 volumio volumio5-onboarding[2437]: time=2026-03-04T19:51:02.775+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.147:51934 @ 0x24a7e00" latency=8.565592ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="completed keyexchange" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=debug msg="completed challenge" Mar 04 19:51:02 volumio go-librespot[16339]: time="2026-03-04T19:51:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:51:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:51:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:51:02 volumio volumio[15992]: info: No valid Plugin REST Endpoint Mar 04 19:51:02 volumio qobuz-connect[16371]: 20260304 19:51:02.900 [16371.16371] ERROR SampleApp: api request was not successful Mar 04 19:51:02 volumio qobuz-connect[16371]: Error: Application ID was not specified! Mar 04 19:51:02 volumio qobuz-connect[16371]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Mar 04 19:51:02 volumio qobuz-connect[16371]: -m|--manufacturer: Manufacturer of the device Mar 04 19:51:02 volumio qobuz-connect[16371]: -n|--deviceName: Device name Mar 04 19:51:02 volumio qobuz-connect[16371]: -l|--model: Device's model name Mar 04 19:51:02 volumio qobuz-connect[16371]: -s|--serialNumber: Device's serial number Mar 04 19:51:02 volumio qobuz-connect[16371]: -o|--outputDevice: ALSA PCM device to play audio to Mar 04 19:51:02 volumio qobuz-connect[16371]: -p|--port: Port to run the local configuration HTTP server on Mar 04 19:51:02 volumio qobuz-connect[16371]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Mar 04 19:51:02 volumio qobuz-connect[16371]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Mar 04 19:51:02 volumio qobuz-connect[16371]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Mar 04 19:51:02 volumio qobuz-connect[16371]: -h|--help: Print this help Mar 04 19:51:02 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:51:02 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Mar 04 19:51:02 volumio volumio[15992]: info: Connection to go-librespot Websocket closed Mar 04 19:51:03 volumio volumio[15992]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 04 19:51:03 volumio volumio[15992]: info: MyVolumio token set successfully Mar 04 19:51:03 volumio volumio[15992]: info: MYVOLUMIO: Adding device Mar 04 19:51:03 volumio volumio[15992]: info: MYVOLUMIO: Evaluating Server Mar 04 19:51:03 volumio sudo[16379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 19:51:03 volumio sudo[16379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:03 volumio sudo[16379]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:03 volumio sudo[16381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 19:51:03 volumio sudo[16381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:03 volumio sudo[16381]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:03 volumio volumio[15992]: verbose: New Socket.io Connection to 192.168.178.145 from 192.168.178.147 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 04 19:51:04 volumio volumio[15992]: info: MyVolumio Plan changed: premium Mar 04 19:51:04 volumio volumio[15992]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 04 19:51:04 volumio volumio[15992]: info: Removing browser output: myVolumio user plan is not superstar Mar 04 19:51:04 volumio volumio[15992]: info: Removing audio output: Mar 04 19:51:04 volumio volumio[15992]: info: MYVOLUMIO: Adding device Mar 04 19:51:04 volumio volumio[15992]: info: MYVOLUMIO: Evaluating Server Mar 04 19:51:04 volumio volumio[15992]: info: Remote config written successfully Mar 04 19:51:04 volumio volumio[15992]: info: Starting Tunnel 1 Mar 04 19:51:04 volumio volumio[15992]: info: Starting Tunnel Connection Checker Mar 04 19:51:04 volumio volumio[15992]: info: Completed starting MyVolumio Plugin Mar 04 19:51:04 volumio volumio[15992]: info: MYVolumio Device enabled Mar 04 19:51:04 volumio volumio[15992]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 04 19:51:04 volumio volumio[15992]: info: MyVolumio status changed Mar 04 19:51:04 volumio volumio[15992]: info: Streaming services startup Mar 04 19:51:04 volumio volumio[15992]: info: Starting Streaming Daemon Mar 04 19:51:04 volumio sudo[16435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 19:51:04 volumio sudo[16435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:04 volumio volumio[15992]: info: Setting Geolocation for MyVolumio to eu12 Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio sudo[16438]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 19:51:04 volumio sudo[16438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:04 volumio sudo[16440]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 19:51:04 volumio sudo[16440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:04 volumio sudo[16438]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:04 volumio sudo[16440]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:04 volumio sudo[16435]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:04 volumio volumio[15992]: error: Cannot start Volumio Streaming Daemon Mar 04 19:51:04 volumio volumio[15992]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 04 19:51:04 volumio volumio[15992]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 04 19:51:04 volumio volumio[15992]: info: Setting Geolocation for MyVolumio to eu12 Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:04 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 04 19:51:04 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 04 19:51:05 volumio volumio[15992]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 04 19:51:05 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 04 19:51:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 04 19:51:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:05 volumio go-librespot[16443]: go-librespot daemon starting... Mar 04 19:51:05 volumio go-librespot[16444]: time="2026-03-04T19:51:05+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:05+01:00" level=debug msg="app state loaded" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=info msg="zeroconf server listening on port 41001" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="obtained new client token: AAC+X9Q4EDJGTmTEfH19x1NIXkSvwVMN0U/0kk+mArK5H8eBgySicA7+Gaj5RVneBHAl+tteUKPgoUcBqHIa5aw8rpy3vBcA9u8QFwJFWLSDgs1Kc6TNGpWaMtkA0wLEOL1Y8a6wx9e/4fD4NXBOsLBTPr+CS6TJooaegb5XmTrhpY4XPG2Xob3jDpyojB5vUzhbpoc/9FDs2LfAPoa2ogTM2UNfxFWhSRZPb8KhYCqZhnT9eROZRc0IlA==" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="completed keyexchange" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=debug msg="completed challenge" Mar 04 19:51:06 volumio go-librespot[16444]: time="2026-03-04T19:51:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 19:51:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:51:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:51:07 volumio volumio[15992]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 04 19:51:07 volumio sudo[16454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 04 19:51:07 volumio sudo[16454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:07 volumio sudo[16454]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:07 volumio volumio[15992]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 04 19:51:07 volumio volumio[15992]: info: MRS: MultiRoom plugin initialized Mar 04 19:51:07 volumio volumio[15992]: info: MRS: STOPPING SNAPCLIENT Mar 04 19:51:07 volumio volumio[15992]: info: MRS: Snap server stop Mar 04 19:51:07 volumio volumio[15992]: info: MRS: STOPPING volumioStreaming Mar 04 19:51:07 volumio sudo[16471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 04 19:51:07 volumio sudo[16471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:07 volumio sudo[16473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 04 19:51:07 volumio sudo[16473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:07 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 04 19:51:07 volumio sudo[16478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 04 19:51:07 volumio sudo[16478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:07 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6. Mar 04 19:51:07 volumio sudo[16475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 04 19:51:07 volumio sudo[16475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:07 volumio sudo[16478]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:08 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 04 19:51:08 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 04 19:51:08 volumio sudo[16473]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:08 volumio volumio[15992]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 04 19:51:08 volumio sudo[16475]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:08 volumio sudo[16471]: pam_unix(sudo:session): session closed for user root Mar 04 19:51:08 volumio volumio[15992]: info: Preparing to generate the ALSA configuration file Mar 04 19:51:08 volumio volumio[15992]: verbose: New Socket.io Connection to 192.168.178.145 from 192.168.178.147 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 04 19:51:08 volumio volumio[15992]: info: Getting Spotify volume Mar 04 19:51:08 volumio volumio[15992]: info: Initializing connection to go-librespot Websocket Mar 04 19:51:08 volumio volumio[15992]: info: Updating MyVolumio device info Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: info: Updating MyVolumio device info Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:51:08 volumio volumio[15992]: verbose: New Socket.io Connection to 192.168.178.145 from 192.168.178.147 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 04 19:51:08 volumio volumio[15992]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 04 19:51:08 volumio volumio[15992]: info: Reading ALSA contributions from plugins. Mar 04 19:51:08 volumio volumio[15992]: info: Successfully Added MyVolumio device Mar 04 19:51:08 volumio volumio[15992]: verbose: New Socket.io Connection to 192.168.178.145 from 192.168.178.147 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 04 19:51:08 volumio volumio[15992]: info: Successfully Added MyVolumio device Mar 04 19:51:08 volumio volumio[15992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:51:08 volumio volumio[15992]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 19:51:08 volumio volumio[15992]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 04 19:51:08 volumio volumio[15992]: errno: -111, Mar 04 19:51:08 volumio volumio[15992]: code: 'ECONNREFUSED', Mar 04 19:51:08 volumio volumio[15992]: syscall: 'connect', Mar 04 19:51:08 volumio volumio[15992]: address: '127.0.0.1', Mar 04 19:51:08 volumio volumio[15992]: port: 9879, Mar 04 19:51:08 volumio volumio[15992]: response: undefined Mar 04 19:51:08 volumio volumio[15992]: } Mar 04 19:51:08 volumio volumio[15992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:51:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 04 19:51:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 19:51:09 volumio go-librespot[16496]: go-librespot daemon starting... Mar 04 19:51:09 volumio go-librespot[16497]: time="2026-03-04T19:51:09+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 19:51:09 volumio go-librespot[16497]: time="2026-03-04T19:51:09+01:00" level=debug msg="app state loaded" Mar 04 19:51:09 volumio go-librespot[16497]: time="2026-03-04T19:51:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 19:51:10 volumio sudo[16505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 19:50' Mar 04 19:51:10 volumio sudo[16505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:51:10 volumio go-librespot[16497]: time="2026-03-04T19:51:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 19:51:10 volumio go-librespot[16497]: time="2026-03-04T19:51:10+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 19:51:10 volumio go-librespot[16497]: time="2026-03-04T19:51:10+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"