Aug 26 11:05:38 overkapping-smidt ntpd[924]: CLOCK: time stepped by 2436121.248809 Aug 26 11:05:38 overkapping-smidt ntpd[924]: CLOCK: time changed from 2026-07-29 to 2026-08-26 Aug 26 11:05:38 overkapping-smidt ntpd[924]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 26 11:05:38 overkapping-smidt systemd[1]: Starting apt-daily.service - Daily apt download activities... Aug 26 11:05:38 overkapping-smidt systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 26 11:05:38 overkapping-smidt systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 26 11:05:38 overkapping-smidt systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Aug 26 11:05:38 overkapping-smidt systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 26 11:05:38 overkapping-smidt systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 26 11:05:38 overkapping-smidt systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 26 11:05:38 overkapping-smidt systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 26 11:05:38 overkapping-smidt systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 26 11:05:38 overkapping-smidt systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 26 11:05:38 overkapping-smidt fstrim[26561]: /boot: 272.6 MiB (285851648 bytes) trimmed on /dev/mmcblk0p1 Aug 26 11:05:38 overkapping-smidt systemd[1]: fstrim.service: Deactivated successfully. Aug 26 11:05:38 overkapping-smidt systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "outputs"... Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "albumart"... Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Plugin example_plugin is not enabled Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "inputs"... Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "updater_comm"... Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Plugin mpdemulation is not enabled Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "rest_api"... Aug 26 11:05:38 overkapping-smidt volumio[26469]: info: Loading plugin "websocket"... Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: Starting Socket.io Server version 1.7.4 Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: Loading plugin "fusiondsp"... Aug 26 11:05:39 overkapping-smidt sudo[26533]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: Applying required configuration parameters for plugin fusiondsp Aug 26 11:05:39 overkapping-smidt volumio[26585]: Forking 3 albumart workers Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: Loading i18n strings for locale nl Aug 26 11:05:39 overkapping-smidt volumio[26469]: Updating browse sources language Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::initPlayerControls Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 11:05:39 overkapping-smidt volumio[26469]: Express server listening on port 3000 Aug 26 11:05:39 overkapping-smidt volumio[26469]: [Metrics] WebUI: 6s 469.98ms Aug 26 11:05:39 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:39.716+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" available=true connected=true macAddress=b8:27:eb:1f:a4:a8 ip4Address=192.168.2.67/24 ip6Address= ssid=KPN2C6136_ExtraWiFi2G Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreStateMachine::resetVolumioState Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreStateMachine::getcurrentVolume Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 11:05:39 overkapping-smidt volumio[26469]: info: Volumio Network Manager: Network status updated: 2 Aug 26 11:05:39 overkapping-smidt volumio[26469]: 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 Aug 26 11:05:40 overkapping-smidt volumio[26469]: 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 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Reloading queue from file Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio-remote-updater[692]: [2026-08-26 11:05:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1785299015 101 Aug 26 11:05:40 overkapping-smidt volumio[26469]: 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: 2 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: VolumeController:: Volume=100 Mute =false Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::updateTrackBlock Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrackBlock Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.441+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.442+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::setRepeat null single undefined Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::setRandom null Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.536+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.541+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.553+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.554+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Setting Device type: Raspberry PI Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: DAC script executed Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Completed loading Core Plugins Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: A device disappeared from network Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: A device disappeared from network Aug 26 11:05:40 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to 192.168.2.67:3000 from 192.168.2.4 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: VolumeController:: Volume=100 Mute =false Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.913+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 26 11:05:40 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:40.913+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: adding 52c11bac-ac40-4962-8561-5bd1ef148372 Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: Discovery: Found device Overkapping-Smidt Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:40 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: this is already registered, 52c11bac-ac40-4962-8561-5bd1ef148372 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: Found device Overkapping-Smidt Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:41 overkapping-smidt volumio[26597]: Starting albumart workers Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:41 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to 192.168.2.67:3000 from 192.168.2.4 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 26 11:05:41 overkapping-smidt systemd[1]: apt-daily.service: Deactivated successfully. Aug 26 11:05:41 overkapping-smidt systemd[1]: Finished apt-daily.service - Daily apt download activities. Aug 26 11:05:41 overkapping-smidt systemd[1]: apt-daily.service: Consumed 2.664s CPU time. Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: this is already registered, 52c11bac-ac40-4962-8561-5bd1ef148372 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: Found device Overkapping-Smidt Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: this is already registered, 52c11bac-ac40-4962-8561-5bd1ef148372 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Discovery: Found device Overkapping-Smidt Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Asound.conf file unchanged, so no further update is needed Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:05:41 overkapping-smidt systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:41 overkapping-smidt sudo[26650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:05:41 overkapping-smidt sudo[26650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:41 overkapping-smidt sudo[26652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:05:41 overkapping-smidt sudo[26652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:41 overkapping-smidt sudo[26650]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: ___________ START PLUGINS ___________ Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: ControllerMpd::onStart: Initializing MPD Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Creating MPD Configuration file Aug 26 11:05:41 overkapping-smidt systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 11:05:41 overkapping-smidt sudo[26662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 26 11:05:41 overkapping-smidt sudo[26662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:41 overkapping-smidt sudo[26664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:05:41 overkapping-smidt sudo[26664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:41 overkapping-smidt sudo[26664]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: [1787735141472] CoreMusicLibrary::Adding element Media Servers Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 11:05:41 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:05:41 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:05:41 overkapping-smidt systemd[1]: mpd.service: Consumed 2.775s CPU time. Aug 26 11:05:41 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:05:41 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:05:41 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:05:41 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:05:41 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: UPNP Browser: Client initialized successfully Aug 26 11:05:41 overkapping-smidt sudo[26670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:05:41 overkapping-smidt sudo[26670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:41 overkapping-smidt sudo[26662]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:05:41 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:05:41 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:05:41 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:41 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:05:41 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:05:41 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: [1787735141762] CoreMusicLibrary::Adding element Last_100 Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: [1787735141766] CoreMusicLibrary::Adding element Webradio Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 11:05:41 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Initializing BBC Radios Aug 26 11:05:41 overkapping-smidt volumio[26598]: Starting albumart workers Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: Creating Spotify config file Aug 26 11:05:41 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:41 overkapping-smidt volumio[26596]: Starting albumart workers Aug 26 11:05:42 overkapping-smidt sudo[26694]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:05:42 overkapping-smidt sudo[26694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:05:42 overkapping-smidt sudo[26694]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: FusionDsp - CamillaDSP binary found. Aug 26 11:05:42 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:42.168+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: Loading i18n strings for locale nl Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: FusionDsp - mixtype--------------------- Hardware Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: Volumio Calling Home Aug 26 11:05:42 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Volumio called home Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Spotify config file written Aug 26 11:05:43 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt sudo[26734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 11:05:43 overkapping-smidt sudo[26734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 26 11:05:43 overkapping-smidt systemd[1]: go-librespot-daemon.service: Killing process 26439 (go-librespot) with signal SIGKILL. Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:43 overkapping-smidt systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: No need to fix Spotify hosts Aug 26 11:05:43 overkapping-smidt systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 11:05:43 overkapping-smidt systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 11:05:43 overkapping-smidt go-librespot[26746]: go-librespot daemon starting... Aug 26 11:05:43 overkapping-smidt sudo[26734]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:43 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 26 11:05:43 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:43+02:00" level=debug msg="app state loaded" Aug 26 11:05:43 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:43+02:00" level=debug msg="stored credentials not found" Aug 26 11:05:43 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 11:05:43 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:05:43 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:05:44 overkapping-smidt sudo[26761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:05:44 overkapping-smidt sudo[26761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:44 overkapping-smidt sudo[26759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:05:44 overkapping-smidt sudo[26759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:44 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:44+02: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]" Aug 26 11:05:44 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:44+02: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]" Aug 26 11:05:44 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:44+02: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]" Aug 26 11:05:44 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:44+02:00" level=info msg="zeroconf server listening on port 41379" Aug 26 11:05:44 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:44+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 11:05:44 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:44.095+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s Aug 26 11:05:44 overkapping-smidt sudo[26757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:05:44 overkapping-smidt sudo[26757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Asound.conf file unchanged, so no further update is needed Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:05:44 overkapping-smidt systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:05:44 overkapping-smidt sudo[26774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:05:44 overkapping-smidt sudo[26774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:44 overkapping-smidt systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 11:05:44 overkapping-smidt sudo[26774]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:44 overkapping-smidt sudo[26776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:05:44 overkapping-smidt sudo[26776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:44 overkapping-smidt systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:05:44 overkapping-smidt systemd[1]: shairport-sync.service: Consumed 1.746s CPU time. Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:44.314+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=http://pushupdates.volumio.org duration=214.97499ms Aug 26 11:05:44 overkapping-smidt systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:05:44 overkapping-smidt sudo[26757]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:44 overkapping-smidt sudo[26759]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:44 overkapping-smidt sudo[26761]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:44 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:05:44 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:05:44 overkapping-smidt systemd[1]: mpd.service: Consumed 2.402s CPU time. Aug 26 11:05:44 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:05:44 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:05:44 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:05:44 overkapping-smidt volumio[26469]: error: Upnp client error: Error: read ECONNRESET Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:05:44 overkapping-smidt volumio[26469]: Error adding Membership: Error: addMembership EINVAL Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:05:44 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:05:44 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:05:44 overkapping-smidt systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Aug 26 11:05:44 overkapping-smidt systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Aug 26 11:05:44 overkapping-smidt systemd[1]: apt-daily-upgrade.service: Consumed 2.802s CPU time. Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:44 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:05:44 overkapping-smidt sudo[26802]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:05:44 overkapping-smidt sudo[26802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:05:44 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:44.977+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=881.075335ms Aug 26 11:05:44 overkapping-smidt sudo[26802]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.024+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=913.357042ms Aug 26 11:05:45 overkapping-smidt sudo[26809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:05:45 overkapping-smidt sudo[26809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.094+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://www.googleapis.com duration=990.429597ms Aug 26 11:05:45 overkapping-smidt systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 11:05:45 overkapping-smidt systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 11:05:45 overkapping-smidt systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.162+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.059441335s Aug 26 11:05:45 overkapping-smidt systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:05:45 overkapping-smidt sudo[26809]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:45 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.287+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://google.com duration=1.186534312s Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.658+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.560235862s Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.660+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.548402657s Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.715+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.618346267s Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.737+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.63796701s Aug 26 11:05:45 overkapping-smidt sudo[26844]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 11:05:45 overkapping-smidt sudo[26844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:45 overkapping-smidt sudo[26844]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:45 overkapping-smidt sudo[26846]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 11:05:45 overkapping-smidt sudo[26846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:45 overkapping-smidt sudo[26849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 11:05:45 overkapping-smidt sudo[26849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:45 overkapping-smidt sudo[26846]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:45 overkapping-smidt sudo[26849]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:45 overkapping-smidt volumio[26469]: info: Upmpdcli Daemon Started Aug 26 11:05:45 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:45.884+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=https://database.volumio.cloud duration=1.779231313s Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.466014, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.467902, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468069, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468166, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468239, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468313, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468400, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468475, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468542, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468634, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468705, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468788, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468863, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.468959, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.469044, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.469125, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 26 11:05:46 overkapping-smidt nmbd[1006]: [2026/08/26 11:05:46.469196, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 26 11:05:46 overkapping-smidt nmbd[1006]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 26 11:05:46 overkapping-smidt volumio[26469]: info: go-librespot daemon successfully initialized Aug 26 11:05:47 overkapping-smidt mpd[26811]: 2026-08-26T11:05:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 11:05:47 overkapping-smidt systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 11:05:47 overkapping-smidt sudo[26652]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:47 overkapping-smidt sudo[26670]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:47 overkapping-smidt sudo[26776]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: Completed starting Core Plugins Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: ------------------------------------------- Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: ----- MyVolumio plugins startup ---- Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: ------------------------------------------- Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: MPD error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: MPD error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: info: MPD running with PID26811 Aug 26 11:05:48 overkapping-smidt volumio[26469]: ,establishing connection Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: MPD error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: The expression evaluated to a falsy value: Aug 26 11:05:48 overkapping-smidt volumio[26469]: assert.ok(self.idling) Aug 26 11:05:48 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.698+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=http://cddb.volumio.org duration=4.595908797s Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.721+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-23.653172ms timeout=10s endpoint=http://plugins.volumio.org duration=4.621691037s Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.769+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-19.875364ms timeout=10s from=APP_PAGE_SETUP_V1_INTERNET to=APP_PAGE_LEGACY_DEVICE Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.803+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.816+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.846173ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.817+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=13.184401ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.821+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=17.358924ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.823+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=http://pushupdates.volumio.org duration=18.860848ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.845+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://google.com duration=41.81247ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.912+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://www.googleapis.com duration=108.162233ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.925+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://securetoken.googleapis.com duration=120.510698ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.929+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=124.697721ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.936+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://functions.volumio.cloud duration=131.147761ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.937+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://functions.volumio.cloud duration=132.279113ms Aug 26 11:05:48 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:48.941+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=https://database.volumio.cloud duration=136.627541ms Aug 26 11:05:49 overkapping-smidt sudo[26861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 11:05:49 overkapping-smidt sudo[26861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:49 overkapping-smidt sudo[26863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 11:05:49 overkapping-smidt sudo[26863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:49 overkapping-smidt sudo[26861]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:49 overkapping-smidt sudo[26863]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:49 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.4 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.170 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 11:05:49 overkapping-smidt volumio[26469]: info: Initializing connection to go-librespot Websocket Aug 26 11:05:49 overkapping-smidt go-librespot[26747]: time="2026-08-26T11:05:49+02:00" level=debug msg="new websocket client" Aug 26 11:05:49 overkapping-smidt volumio[26469]: info: Connection to go-librespot Websocket established Aug 26 11:05:50 overkapping-smidt sudo[26867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 11:05:50 overkapping-smidt sudo[26867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:50 overkapping-smidt sudo[26867]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:50 overkapping-smidt sudo[26869]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 11:05:50 overkapping-smidt sudo[26869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:50 overkapping-smidt sudo[26869]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:50 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.4 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.170 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetQueue Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreStateMachine::getQueue Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CorePlayQueue::getQueue Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: Listing playlists Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 11:05:50 overkapping-smidt volumio[26469]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 11:05:51 overkapping-smidt volumio[26469]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 26 11:05:51 overkapping-smidt volumio[26469]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 26 11:05:51 overkapping-smidt volumio[26469]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: camilladsp service started and running in background, instance 1 Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp loaded Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - --[object Object] Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: Adding Signal Path Element [object Object] Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: Adding fusiondspeq DSP Signal Path Element Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Aug 26 11:05:51 overkapping-smidt volumio[26469]: info: FusionDsp - PEQ graph server listening on port 10015 Aug 26 11:05:51 overkapping-smidt volumio[26469]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 26 11:05:51 overkapping-smidt sudo[26890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 26 11:05:51 overkapping-smidt sudo[26890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:51 overkapping-smidt sudo[26890]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPlay Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreStateMachine::play index undefined Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CoreStateMachine::startPlaybackTimer Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: [1787735152890] ControllerWebradio::clearAddPlayTrack Aug 26 11:05:52 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: sendMpdCommand stop took 2 milliseconds Aug 26 11:05:52 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: sendMpdCommand clear took 1 milliseconds Aug 26 11:05:52 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand load "https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Aug 26 11:05:52 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Aug 26 11:05:52 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Aug 26 11:05:52 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Aug 26 11:05:52 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:52 overkapping-smidt volumio[26469]: info: Getting Spotify volume Aug 26 11:05:53 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:53 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 26 11:05:53 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME undefined Aug 26 11:05:53 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 100 Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: Aligning Spotify Volume to Volumio Volume Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 100 Aug 26 11:05:53 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 26 11:05:54 overkapping-smidt volumio[26469]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 11:05:54 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:05:54 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:05:54 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:05:54 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:05:54 overkapping-smidt volumio[26469]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 26 11:05:54 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand add "https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 2059ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 2057ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 2056ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 2055ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Aug 26 11:05:54 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: sendMpdCommand add "https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" took 6 milliseconds Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 11:05:54 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand play Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Aug 26 11:05:54 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Aug 26 11:05:54 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Aug 26 11:05:54 overkapping-smidt volumio[26469]: ---------------------------- MPD announces system playlist update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: Ignoring MPD Status Update Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 13ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: sendMpdCommand play took 11 milliseconds Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 9ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 7ms Aug 26 11:05:54 overkapping-smidt volumio[26469]: info: ------------------------------ 6ms Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin bluetooth to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin multiroom to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin metavolumio to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin cd_controller to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 26 11:05:56 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: MyVolumio login type: Token Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 11:05:57 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 26 11:05:58 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:58.625+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-17.846003ms timeout=3s volume=99 Aug 26 11:05:58 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:58.803+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=http://cddb.volumio.org duration=9.999227929s error="Head \"http://cddb.volumio.org\": context deadline exceeded" Aug 26 11:05:58 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:58.803+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.931369ms timeout=10s endpoint=http://plugins.volumio.org duration=9.998714648s error="Head \"http://plugins.volumio.org\": context deadline exceeded" Aug 26 11:05:58 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:58.848+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-35.165114ms timeout=3s volume=99 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.049+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-32.185708ms timeout=3s volume=98 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Streaming services startup Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Starting Streaming Daemon Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 26 11:05:59 overkapping-smidt sudo[26928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 11:05:59 overkapping-smidt sudo[26928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:05:59 overkapping-smidt sudo[26928]: pam_unix(sudo:session): session closed for user root Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.247+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-34.050323ms timeout=3s volume=97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 6 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 6 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume99 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume99 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume98 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.342+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.343+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=6353 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.344+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id= title= Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.344+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.345+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=6353 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.345+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id= title= Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.350+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.350+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.351+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=6353 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.351+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=6353 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.351+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id= title= Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.353+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id= title= Aug 26 11:05:59 overkapping-smidt volumio[26469]: error: Cannot start Volumio Streaming Daemon Aug 26 11:05:59 overkapping-smidt volumio[26469]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 11:05:59 overkapping-smidt volumio[26469]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Aug 26 11:05:59 overkapping-smidt volumio[26469]: ---------------------------- MPD announces state update: player Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::getState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand status Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 140 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 139 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 138 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 136 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 136 milliseconds Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.408+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-31.580295ms timeout=3s volume=96 Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3987,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus stop Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3987,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:05:59 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ------------------------------ 180ms Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.440+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=3987 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.441+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.442+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=3987 volume=97 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.443+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: ------------------------------ 199ms Aug 26 11:05:59 overkapping-smidt volumio[26469]: Upnp client error: Error: This socket has been ended by the other party Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is not playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Clipped samples monitor stopped Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is not playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Clipped samples monitor stopped Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is not playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Clipped samples monitor stopped Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is not playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Clipped samples monitor stopped Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 100 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - ---- read samplerate from file: 48000 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: camilladsp stopping service pid 26883... Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.554+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.572562ms timeout=3s volume=96 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.756+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-25.749549ms timeout=3s volume=92 Aug 26 11:05:59 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:05:59.946+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-35.811037ms timeout=3s volume=88 Aug 26 11:05:59 overkapping-smidt volumio[26469]: grep: /proc/26883/cmdline: binary file matches Aug 26 11:05:59 overkapping-smidt volumio[26469]: grep: /proc/26883/cmdline: binary file matches Aug 26 11:05:59 overkapping-smidt volumio[26469]: grep: /proc/26883/cmdline: binary file matches Aug 26 11:05:59 overkapping-smidt volumio[26469]: grep: /proc/26883/cmdline: binary file matches Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: camilladsp service terminated, instance 1 Aug 26 11:05:59 overkapping-smidt volumio[26469]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: camilladsp service started and running in background, instance 1 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 647 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 646 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand status took 645 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 639 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 640 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 639 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseState Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3987,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3987,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3987,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.082+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.083+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.084+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.085+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.087+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.088+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.089+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.089+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.092+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.093+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.093+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=97 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.093+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 865ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 864ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 864ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume96 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume96 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume92 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.151+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-34.300138ms timeout=3s volume=84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.194+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.194+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.196+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.198+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.199+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.201+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.201+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 185 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 184 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: sendMpdCommand playlistinfo took 184 milliseconds Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: ControllerMpd::parseTrackInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4229,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4229,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ControllerMpd::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::servicePushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4229,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","artist":null,"album":null,"uri":"https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y","trackType":""} Aug 26 11:06:00 overkapping-smidt volumio[26469]: verbose: CURRENT POSITION 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState stateService play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::syncState currentStatus play Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Received an update from plugin. extracting info from payload Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.261+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.262+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.263+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.263+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.265+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.265+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.267+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.268+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.272+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.273+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=88 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.274+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 911ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 910ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: ------------------------------ 909ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.335+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=4786 volume=84 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.336+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.360+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-34.396307ms timeout=3s volume=81 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 97 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 88 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume81 Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.510+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=5219 volume=81 Aug 26 11:06:00 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:00.512+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 84 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 81 Aug 26 11:06:00 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 81 Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:00 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor started Aug 26 11:06:00 overkapping-smidt volumio[26469]: error: MyVolumio Custom Token format not valid, refreshing it Aug 26 11:06:01 overkapping-smidt volumio[26469]: info: MyVolumio login type: Token Aug 26 11:06:01 overkapping-smidt volumio[26469]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 26 11:06:02 overkapping-smidt volumio[26469]: SPOTIFY: SETTING SPOTIFY VOLUME 81 Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MyVolumio token set successfully Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MYVOLUMIO: Adding device Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MYVOLUMIO: Evaluating Server Aug 26 11:06:02 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:02.738+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=8.599793ms timeout=3s volume=80 Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MyVolumio Plan changed: premium Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Removing browser output: myVolumio user plan is not superstar Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Removing audio output: Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MYVOLUMIO: Adding device Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: MYVOLUMIO: Evaluating Server Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume80 Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:02 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:02.825+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=7504 volume=80 Aug 26 11:06:02 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:02.826+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:02 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Aug 26 11:06:02 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 81 Aug 26 11:06:02 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 80 Aug 26 11:06:02 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Remote config written successfully Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Starting Tunnel 1 Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Starting Tunnel Connection Checker Aug 26 11:06:02 overkapping-smidt volumio[26469]: info: Completed starting MyVolumio Plugin Aug 26 11:06:02 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:02.962+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-22.142387ms timeout=3s volume=79 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: MYVolumio Device enabled Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: MyVolumio status changed Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Streaming services startup Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Starting Streaming Daemon Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume79 Aug 26 11:06:03 overkapping-smidt sudo[27012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 11:06:03 overkapping-smidt sudo[27012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.118+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=7797 volume=79 Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.119+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 81 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 79 Aug 26 11:06:03 overkapping-smidt sudo[27012]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 79 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 26 11:06:03 overkapping-smidt volumio[26469]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 26 11:06:03 overkapping-smidt volumio[26469]: error: Cannot start Volumio Streaming Daemon Aug 26 11:06:03 overkapping-smidt volumio[26469]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 11:06:03 overkapping-smidt volumio[26469]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.162+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-22.076566ms timeout=3s volume=75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.185+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=7797 volume=75 Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.186+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 79 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Setting Geolocation for MyVolumio to eu12 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.349+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-35.090321ms timeout=3s volume=73 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume73 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=8048 volume=73 Aug 26 11:06:03 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:03.387+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 75 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 73 Aug 26 11:06:03 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 73 Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 26 11:06:03 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Aug 26 11:06:03 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 26 11:06:05 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:05.355+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-34.613828ms timeout=3s volume=68 Aug 26 11:06:05 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:05.555+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-36.255236ms timeout=3s volume=53 Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 26 11:06:05 overkapping-smidt sudo[27020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 26 11:06:05 overkapping-smidt sudo[27020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:05 overkapping-smidt sudo[27020]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: MRS: MultiRoom plugin initialized Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: MRS: STOPPING SNAPCLIENT Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: MRS: Snap server stop Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: MRS: STOPPING volumioStreaming Aug 26 11:06:05 overkapping-smidt sudo[27050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 26 11:06:05 overkapping-smidt sudo[27050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:05 overkapping-smidt sudo[27053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 26 11:06:05 overkapping-smidt sudo[27053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 26 11:06:05 overkapping-smidt sudo[27055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 26 11:06:05 overkapping-smidt sudo[27055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:05 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:05.757+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-34.569447ms timeout=3s volume=47 Aug 26 11:06:05 overkapping-smidt sudo[27058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 26 11:06:05 overkapping-smidt sudo[27058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:05 overkapping-smidt sudo[27058]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 26 11:06:05 overkapping-smidt sudo[27050]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 26 11:06:05 overkapping-smidt sudo[27053]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:05 overkapping-smidt sudo[27055]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 26 11:06:05 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:05.952+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-39.242599ms timeout=3s volume=41 Aug 26 11:06:05 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Updating MyVolumio device info Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Successfully Added MyVolumio device Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Setting Geolocation for MyVolumio to eu5 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume68 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume53 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume47 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.147+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=10800 volume=41 Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.147+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=10800 volume=41 Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.154+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.157+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=10800 volume=41 Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.157+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.158+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=10800 volume=41 Aug 26 11:06:06 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:06.159+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: Removed streaming files Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: volumioStreaming STOPPED Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: SNAPSERVER STOPPED Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: SNAPCLIENT STOPPED Aug 26 11:06:06 overkapping-smidt volumio[26469]: Upnp client error: Error: This socket has been ended by the other party Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 73 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Asound.conf file written Aug 26 11:06:06 overkapping-smidt sudo[27071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 11:06:06 overkapping-smidt sudo[27071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:06 overkapping-smidt sudo[27071]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:06 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 11:06:06 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 11:06:06 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 11:06:06 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 26 11:06:06 overkapping-smidt volumio[26469]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Aug 26 11:06:06 overkapping-smidt volumio[26469]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Aug 26 11:06:06 overkapping-smidt volumio[26469]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:06 overkapping-smidt sudo[27077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:06:06 overkapping-smidt sudo[27077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:06 overkapping-smidt sudo[27077]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:06 overkapping-smidt sudo[27080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:06:06 overkapping-smidt sudo[27080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 26 11:06:06 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 26 11:06:06 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 26 11:06:06 overkapping-smidt systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 11:06:06 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 26 11:06:06 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Aug 26 11:06:06 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output for this device Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:06 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:06:06 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:06:06 overkapping-smidt bluetoothd[857]: Path / reserved for Adv Monitor app :1.398 Aug 26 11:06:06 overkapping-smidt systemd[1]: mpd.service: Consumed 4.795s CPU time. Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 26 11:06:06 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding METAVOLUMIO REST API Endpoints Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 26 11:06:06 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:06:06 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Preparing CD Folders Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding CD REST API Endpoints Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Starting UDEV Watcher for CD Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: Detecting CD presence with UDEV Aug 26 11:06:06 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 26 11:06:06 overkapping-smidt bluetoothd[857]: Adv Monitor app :1.398 disconnected from D-Bus Aug 26 11:06:06 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:06:06 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:06:06 overkapping-smidt sudo[27096]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:06:06 overkapping-smidt sudo[27096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:06:06 overkapping-smidt sudo[27096]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:09 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:09.220+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-29.468378ms timeout=3s volume=45 Aug 26 11:06:09 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:09.411+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-38.894456ms timeout=3s volume=54 Aug 26 11:06:09 overkapping-smidt mpd[27099]: 2026-08-26T11:06:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 11:06:09 overkapping-smidt systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 11:06:09 overkapping-smidt sudo[27080]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:10 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:10.793+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-16.712649ms timeout=3s volume=55 Aug 26 11:06:11 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:11.595+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-37.700293ms timeout=3s volume=56 Aug 26 11:06:11 overkapping-smidt volumio[26469]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding inputs REST Endpoints Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Scanning Audio Inputs Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Checking against Known Cards name Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding Server instance for streaming Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 26 11:06:11 overkapping-smidt volumio[26469]: error: Hi Res Audio Failed Login: Missing Login Data Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 26 11:06:11 overkapping-smidt sudo[27107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 26 11:06:11 overkapping-smidt sudo[27107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding TIDAL REST API Endpoints Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Stopping AccessToken refresher cron for QOBUZ Aug 26 11:06:11 overkapping-smidt sudo[27114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 11:06:11 overkapping-smidt sudo[27114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: AccessToken refresher cron started for QOBUZ Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Adding QOBUZ REST API Endpoints Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: MRS: Getting audio outputs on start Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: MRS: Requesting all other devices output Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Updating MyVolumio device info Aug 26 11:06:11 overkapping-smidt sudo[27107]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:11 overkapping-smidt volumio[26469]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 11:06:11 overkapping-smidt sudo[27114]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:11 overkapping-smidt sudo[27117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 11:06:11 overkapping-smidt sudo[27117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Successfully Updated MyVolumio device Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Received Get System Version Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Received Get System Info Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: Discovery: Getting this device information Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume45 Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume54 Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume55 Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume56 Aug 26 11:06:11 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:11.970+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" latency=-16.835386ms timeout=3s volume=57 Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:11 overkapping-smidt systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:11 overkapping-smidt sudo[27117]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:11 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.022+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=16563 volume=56 Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.022+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.036+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=16563 volume=56 Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.037+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.039+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=16563 volume=56 Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.039+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=16563 volume=56 Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.040+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.041+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 26 11:06:12 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: camilladsp respawn in 200 ms (attempt 2/10) Aug 26 11:06:12 overkapping-smidt sudo[27125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 26 11:06:12 overkapping-smidt sudo[27125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Successfully Added MyVolumio device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:12 overkapping-smidt systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 26 11:06:12 overkapping-smidt sudo[27125]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 41 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Setting Spotify Volume from Volumio: 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: VolumeController::SetAlsaVolume57 Aug 26 11:06:12 overkapping-smidt volumiobt[27131]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.332+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_PLAYING positionMs=16956 volume=57 Aug 26 11:06:12 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:12.332+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:12 overkapping-smidt sudo[27133]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 26 11:06:12 overkapping-smidt sudo[27133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:12 overkapping-smidt sudo[27133]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:12 overkapping-smidt sudo[27135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 26 11:06:12 overkapping-smidt sudo[27135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:12 overkapping-smidt sudo[27135]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is playing Aug 26 11:06:12 overkapping-smidt volumiobt[27139]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 56 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 57 Aug 26 11:06:12 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 26 11:06:12 overkapping-smidt volumio-remote-updater[692]: Test mode disabled Aug 26 11:06:12 overkapping-smidt volumio-remote-updater[692]: Alpha mode disabled Aug 26 11:06:12 overkapping-smidt volumio-remote-updater[692]: Alpha legacy test mode disabled Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt bluetoothd[857]: Adv Monitor app :1.399 disconnected from D-Bus Aug 26 11:06:12 overkapping-smidt volumiobt[27144]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Executing endpoint qc_getconfig Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [198B blob data] Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [162B blob data] Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [162B blob data] Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [162B blob data] Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [118B blob data] Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [5D-B7-55-E9-F7-BB]> discoverable on Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [5D-B7-55-E9-F7-BB]> pairable on Aug 26 11:06:12 overkapping-smidt bluetoothd[857]: Path / reserved for Adv Monitor app :1.400 Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.554 [27119.27119] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 26 11:06:12 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 26 11:06:12 overkapping-smidt bluetoothd[857]: Adv Monitor app :1.400 disconnected from D-Bus Aug 26 11:06:12 overkapping-smidt volumio[26469]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumiobt[27145]: [5D-B7-55-E9-F7-BB]> Aug 26 11:06:12 overkapping-smidt volumiobt[27149]: INFO [BTSTART] Registering Bluetooth agent... Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:12 overkapping-smidt volumiobt[27152]: [NEW] Media /org/bluez/hci0 Aug 26 11:06:12 overkapping-smidt volumiobt[27152]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt volumiobt[27152]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt volumiobt[27152]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: updateQueue error: null Aug 26 11:06:12 overkapping-smidt bluetoothd[857]: Adv Monitor app :1.401 disconnected from D-Bus Aug 26 11:06:12 overkapping-smidt volumiobt[27162]: No agent is registered Aug 26 11:06:12 overkapping-smidt volumiobt[27162]: [NEW] Media /org/bluez/hci0 Aug 26 11:06:12 overkapping-smidt volumiobt[27162]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt volumiobt[27162]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt volumiobt[27162]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.746 [27119.27119] INFO VolumeManager: [0x169e118]: Setting new playback volume: 75 Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.746 [27119.27119] INFO VolumeManager: [0x169e118]: Setting new mute state: 0 Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.746 [27119.27119] INFO AudioStreamManager: [0x169de70]: Setting new audio download buffer size: 1048576 Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.746 [27119.27119] INFO QobuzConnect: [0x169e9e0]: Client initialized! Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.746 [27119.27119] INFO SampleApp: Starting Avahi advertising, name: Overkapping-Smidt, service name: _qobuz-connect._tcp Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Successfully Updated MyVolumio device Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 26 11:06:12 overkapping-smidt bluetoothd[857]: Adv Monitor app :1.402 disconnected from D-Bus Aug 26 11:06:12 overkapping-smidt volumiobt[27164]: INFO [BTSTART] Agent registered successfully. Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.780 [27119.27119] INFO LocalConfigManager: [0x169db98]: Starting Local Configuration server Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.780 [27119.27119] INFO SampleApp: Starting Local configuration server Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.781 [27119.27119] INFO SampleApp: Connected to UNIX socket client 0x1688818 Aug 26 11:06:12 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:12.781 [27119.27119] INFO SampleApp: Playback volume changed: 75 Aug 26 11:06:12 overkapping-smidt volumiobt[27165]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 11:06:12 overkapping-smidt volumio[26469]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:06:12 overkapping-smidt sudo[27170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 26 11:06:12 overkapping-smidt sudo[27170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:12 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:06:12 overkapping-smidt sudo[27176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:06:12 overkapping-smidt sudo[27176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:12 overkapping-smidt systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 11:06:12 overkapping-smidt systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 11:06:13 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:06:13 overkapping-smidt systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 11:06:13 overkapping-smidt sudo[27170]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:13 overkapping-smidt systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 11:06:13 overkapping-smidt systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 11:06:13 overkapping-smidt systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:13 overkapping-smidt systemd[1]: shairport-sync.service: Consumed 1.613s CPU time. Aug 26 11:06:13 overkapping-smidt systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:13 overkapping-smidt sudo[27176]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:13 overkapping-smidt autossh[27181]: port set to 0, monitoring disabled Aug 26 11:06:13 overkapping-smidt autossh[27181]: starting ssh (count 1) Aug 26 11:06:13 overkapping-smidt autossh[27181]: ssh child pid is 27185 Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Remote SSH Started Aug 26 11:06:13 overkapping-smidt volumiossh-tunnel[27185]: Warning: Permanently added '[eu5.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: MRS: Found cast device: KPN-DIW7022-644decf38582f130168fd7b3cf0d2483 Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: MRS: Found cast device: Google-Nest-Hub-6c07827d77310f8cb1dd10377d21abc3 Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: MRS: Found cast device: Google-Nest-Hub-2790fed8e406f5b8886faa1a779fbc4f Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:13 overkapping-smidt volumio[26469]: SPOTIFY: SETTING SPOTIFY VOLUME 56 Aug 26 11:06:13 overkapping-smidt volumio[26469]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 11:06:13 overkapping-smidt volumio[26469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: MRS: Found cast device: Google-Nest-Mini-b74df9bd14b61b7d18ce6b04f88cacd7 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Adding audio output: Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Asound.conf file written Aug 26 11:06:14 overkapping-smidt sudo[27203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 11:06:14 overkapping-smidt sudo[27203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27203]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:14 overkapping-smidt sudo[27209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:06:14 overkapping-smidt sudo[27209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27209]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt sudo[27211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:06:14 overkapping-smidt sudo[27211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: QobuzConnect: setDeactiveState invoked Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:06:14 overkapping-smidt systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 11:06:14 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:06:14 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:06:14 overkapping-smidt systemd[1]: mpd.service: Consumed 2.747s CPU time. Aug 26 11:06:14 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:06:14 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:06:14 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:06:14 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:06:14 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:06:14 overkapping-smidt sudo[27226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 11:06:14 overkapping-smidt sudo[27226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt sudo[27226]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Asound.conf file written Aug 26 11:06:14 overkapping-smidt sudo[27230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 11:06:14 overkapping-smidt sudo[27230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:06:14 overkapping-smidt sudo[27236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27227]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:06:14 overkapping-smidt sudo[27227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:06:14 overkapping-smidt sudo[27239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 11:06:14 overkapping-smidt sudo[27239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27227]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt sudo[27239]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:14 overkapping-smidt sudo[27252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:06:14 overkapping-smidt sudo[27252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:14 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:14.916 [27119.27119] INFO SampleApp: Stopping Local configuration server Aug 26 11:06:14 overkapping-smidt systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: QobuzConnect: setDeactiveState invoked Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:14 overkapping-smidt volumio[26469]: info: Preparing to generate the ALSA configuration file Aug 26 11:06:14 overkapping-smidt sudo[27249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:06:14 overkapping-smidt sudo[27249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:14 overkapping-smidt sudo[27249]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:14 overkapping-smidt systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 11:06:14 overkapping-smidt systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:15 overkapping-smidt systemd[1]: shairport-sync.service: Consumed 1.410s CPU time. Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Reading ALSA contributions from plugins. Aug 26 11:06:15 overkapping-smidt sudo[27266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 11:06:15 overkapping-smidt sudo[27266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: TidalConnect service stoped! Aug 26 11:06:15 overkapping-smidt systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:15 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:06:15 overkapping-smidt sudo[27236]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:06:15 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:06:15 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:06:15 overkapping-smidt sudo[27266]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Shairport-Sync Started Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Starting Shairport Sync Aug 26 11:06:15 overkapping-smidt sudo[27294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 11:06:15 overkapping-smidt sudo[27294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Asound.conf file unchanged, so no further update is needed Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Output device has changed, restarting MPD Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Output device has changed, restarting Shairport Sync Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:15 overkapping-smidt sudo[27303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 11:06:15 overkapping-smidt sudo[27303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt sudo[27303]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt sudo[27300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 11:06:15 overkapping-smidt sudo[27300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: QobuzConnect: setDeactiveState invoked Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:15 overkapping-smidt sudo[27306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 11:06:15 overkapping-smidt sudo[27291]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:06:15 overkapping-smidt sudo[27291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:06:15 overkapping-smidt sudo[27306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt sudo[27291]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 11:06:15 overkapping-smidt systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:15 overkapping-smidt sudo[27314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 26 11:06:15 overkapping-smidt sudo[27314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt sudo[27318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 26 11:06:15 overkapping-smidt sudo[27318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt sudo[27327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 11:06:15 overkapping-smidt sudo[27327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt sudo[27329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 26 11:06:15 overkapping-smidt sudo[27329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: MPD Permissions set Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioNext Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::next Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::stop Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::stPlaybackTimer Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::updateTrackBlock Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrackBlock Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::pushState Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioPushState Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Connecting to system D-Bus Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Connected to system D-Bus Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output update for this device Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Aug 26 11:06:15 overkapping-smidt sudo[27327]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: MRS: Pushing multiroomSync output Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::volumioGetState Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreStateMachine::serviceStop Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CorePlayQueue::getTrack 0 Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::serviceStop Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: [1787735175837] ControllerWebradio::stop Aug 26 11:06:15 overkapping-smidt volumio[26469]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 11:06:15 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:15.841+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" state=STATUS_STOPPED positionMs=0 volume=57 Aug 26 11:06:15 overkapping-smidt volumio5-onboarding[1530]: time=2026-08-26T11:06:15.843+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%05,192.168.2.4:48246 @ 0x2000bd0" id="https://stream.qmusic.nl/qmusic/mp3/?source=tunein&player=rp_external&gdpr=1&gdpr_consent=&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NTc0MDcxMCwiaXNzIjoidGlzcnYifQ.ddyLq7hToC-RE774Wq2ceuLB22KHW99GAmL3GP5M15Y" title=Qmusic Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Enabled Discoverable mode Aug 26 11:06:15 overkapping-smidt sudo[27346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 11:06:15 overkapping-smidt sudo[27346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Agent registered at /local/a2dpagent Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] Agent set as default Aug 26 11:06:15 overkapping-smidt volumiobt[27168]: 2026-08-26 11:06:15 a2dp-agent [INFO] A2DP agent running, waiting for connections... Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 11:06:15 overkapping-smidt sudo[27300]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 11:06:15 overkapping-smidt systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: FusionDsp - Volumio is not playing Aug 26 11:06:15 overkapping-smidt volumio[26469]: info: FusionDsp - Clipped samples monitor stopped Aug 26 11:06:15 overkapping-smidt volumio[26469]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 26 11:06:15 overkapping-smidt volumio[26469]: SPOTIFY: SPOTIFY VOLUME 56 Aug 26 11:06:15 overkapping-smidt volumio[26469]: SPOTIFY: VOLUMIO VOLUME 57 Aug 26 11:06:15 overkapping-smidt volumio[26469]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 26 11:06:15 overkapping-smidt systemd[1]: mpd.service: Deactivated successfully. Aug 26 11:06:15 overkapping-smidt systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 11:06:15 overkapping-smidt sudo[27314]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:15 overkapping-smidt sudo[27318]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:16 overkapping-smidt volumio[26469]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 26 11:06:16 overkapping-smidt volumio[26469]: Job for vtcs.service canceled. Aug 26 11:06:16 overkapping-smidt volumio[26469]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\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)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Aug 26 11:06:16 overkapping-smidt volumio[26469]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 11:06:16 overkapping-smidt sudo[27329]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:16 overkapping-smidt volumio[26469]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 26 11:06:16 overkapping-smidt volumio[26469]: Job for vtcs.service canceled. Aug 26 11:06:16 overkapping-smidt volumio[26469]: at ChildProcess.exithandler (node:child_process:421:12) Aug 26 11:06:16 overkapping-smidt volumio[26469]: at ChildProcess.emit (node:events:514:28) Aug 26 11:06:16 overkapping-smidt volumio[26469]: at maybeClose (node:internal/child_process:1105:16) Aug 26 11:06:16 overkapping-smidt volumio[26469]: at Socket. (node:internal/child_process:457:11) Aug 26 11:06:16 overkapping-smidt volumio[26469]: at Socket.emit (node:events:514:28) Aug 26 11:06:16 overkapping-smidt volumio[26469]: at Pipe. (node:net:337:12) { Aug 26 11:06:16 overkapping-smidt volumio[26469]: code: 1, Aug 26 11:06:16 overkapping-smidt volumio[26469]: killed: false, Aug 26 11:06:16 overkapping-smidt volumio[26469]: signal: null, Aug 26 11:06:16 overkapping-smidt volumio[26469]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Aug 26 11:06:16 overkapping-smidt volumio[26469]: stdout: '', Aug 26 11:06:16 overkapping-smidt volumio[26469]: stderr: 'Job for vtcs.service canceled.\n' Aug 26 11:06:16 overkapping-smidt volumio[26469]: } Aug 26 11:06:16 overkapping-smidt volumio[26469]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 11:06:16 overkapping-smidt systemd[1]: mpd.socket: Deactivated successfully. Aug 26 11:06:16 overkapping-smidt systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 11:06:16 overkapping-smidt systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 11:06:16 overkapping-smidt systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 11:06:16 overkapping-smidt systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 11:06:16 overkapping-smidt sudo[27384]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 11:06:16 overkapping-smidt sudo[27384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 11:06:16 overkapping-smidt sudo[27384]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:16 overkapping-smidt qobuz-connect[27119]: 20260826 11:06:16.789 [27119.27119] INFO SampleApp: shat down connection on UNIX socket Aug 26 11:06:16 overkapping-smidt systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 26 11:06:16 overkapping-smidt systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 11:06:16 overkapping-smidt systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 11:06:16 overkapping-smidt sudo[27346]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:16 overkapping-smidt sudo[27294]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:16 overkapping-smidt sudo[27230]: pam_unix(sudo:session): session closed for user root Aug 26 11:06:17 overkapping-smidt sudo[27391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 11:05' Aug 26 11:06:17 overkapping-smidt sudo[27391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"