May 25 02:24:19 motivo ntpd[1091]: CLOCK: time stepped by 785568.854148 May 25 02:24:19 motivo ntpd[1091]: CLOCK: time changed from 2026-05-16 to 2026-05-25 May 25 02:24:19 motivo ntpd[1091]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 25 02:24:20 motivo systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 25 02:24:20 motivo systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 25 02:24:20 motivo systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 25 02:24:20 motivo systemd[1]: e2scrub_all.service: Deactivated successfully. May 25 02:24:20 motivo systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 25 02:24:20 motivo systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 25 02:24:20 motivo systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 25 02:24:20 motivo systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 25 02:24:20 motivo systemd[1]: Reloading. May 25 02:24:20 motivo sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 25 02:24:20 motivo sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:20 motivo sudo[1869]: pam_unix(sudo:session): session closed for user root May 25 02:24:20 motivo volumio[1283]: info: MRS: MultiRoom plugin initialized May 25 02:24:20 motivo volumio[1283]: info: MRS: STOPPING SNAPCLIENT May 25 02:24:20 motivo volumio[1283]: info: MRS: Snap server stop May 25 02:24:20 motivo volumio[1283]: info: MRS: STOPPING volumioStreaming May 25 02:24:20 motivo sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 25 02:24:20 motivo sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:20 motivo sudo[1905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 25 02:24:20 motivo sudo[1905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:20 motivo sudo[1908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 25 02:24:20 motivo sudo[1908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:20 motivo volumio[1283]: info: Loading plugin "outputs"... May 25 02:24:20 motivo volumio[1283]: info: Loading plugin "albumart"... May 25 02:24:20 motivo sudo[1911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 02:24:20 motivo sudo[1911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:20 motivo volumio[1283]: info: Plugin example_plugin is not enabled May 25 02:24:20 motivo volumio[1283]: info: Loading plugin "hi_res_audio"... May 25 02:24:20 motivo sudo[1911]: pam_unix(sudo:session): session closed for user root May 25 02:24:20 motivo volumio[1914]: Forking 3 albumart workers May 25 02:24:21 motivo sudo[1903]: pam_unix(sudo:session): session closed for user root May 25 02:24:21 motivo sudo[1905]: pam_unix(sudo:session): session closed for user root May 25 02:24:21 motivo sudo[1908]: pam_unix(sudo:session): session closed for user root May 25 02:24:21 motivo firststart.sh[1954]: ssh.socket is a disabled or a static unit not running, not starting it. May 25 02:24:21 motivo firststart.sh[1284]: Enabling SSH for first boot May 25 02:24:21 motivo systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... May 25 02:24:21 motivo volumio[1283]: info: Loading plugin "inputs"... May 25 02:24:21 motivo sshd[1963]: Server listening on 0.0.0.0 port 22. May 25 02:24:21 motivo sshd[1963]: Server listening on :: port 22. May 25 02:24:21 motivo systemd[1]: Started ssh.service - OpenBSD Secure Shell server. May 25 02:24:21 motivo firststart.sh[1284]: Disabling firststart service May 25 02:24:22 motivo systemctl[1964]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". May 25 02:24:22 motivo systemd[1]: Reloading. May 25 02:24:22 motivo volumio[1925]: Starting albumart workers May 25 02:24:22 motivo volumio[1924]: Starting albumart workers May 25 02:24:22 motivo volumio[1926]: Starting albumart workers May 25 02:24:22 motivo volumio[1283]: info: Loading plugin "qobuz"... May 25 02:24:23 motivo firststart.sh[1284]: Finalizing May 25 02:24:23 motivo systemd[1]: firststart.service: Deactivated successfully. May 25 02:24:23 motivo systemd[1]: firststart.service: Consumed 9.674s CPU time. May 25 02:24:23 motivo volumio[1283]: info: Loading plugin "smart_inputs"... May 25 02:24:24 motivo volumio[1283]: info: Loading plugin "tidal"... May 25 02:24:24 motivo volumio[1283]: info: Loading plugin "motivocontrol"... May 25 02:24:24 motivo volumio[1283]: info: Initializing System Ready GPIO for kernel version: 6.12.75-v8+ May 25 02:24:24 motivo volumio[1283]: info: Adding this device properties May 25 02:24:24 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 25 02:24:24 motivo volumio[1283]: info: Setting Additional Device Volumio Properties: [object Object] May 25 02:24:24 motivo volumio[1283]: info: Checking For Motivo Hardware May 25 02:24:24 motivo volumio[1283]: info: Loading plugin "updater_comm"... May 25 02:24:25 motivo volumio[1283]: info: Plugin mpdemulation is not enabled May 25 02:24:25 motivo volumio[1283]: info: Loading plugin "rest_api"... May 25 02:24:25 motivo volumio[1283]: info: Loading plugin "websocket"... May 25 02:24:25 motivo volumio[1283]: info: Starting Socket.io Server version 1.7.4 May 25 02:24:25 motivo volumio[1283]: info: Loading i18n strings for locale en May 25 02:24:25 motivo volumio[1283]: Updating browse sources language May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::initPlayerControls May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: VolumeController:: Starting alsactl monitor May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 02:24:25 motivo volumio[1283]: Express server listening on port 3000 May 25 02:24:25 motivo volumio[1283]: [Metrics] WebUI: 14s 867.50ms May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::resetVolumioState May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::getcurrentVolume May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioRetrievevolume May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::getcurrentVolume May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioRetrievevolume May 25 02:24:25 motivo sudo[2041]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 02:24:25 motivo sudo[2041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo volumio[1283]: info: Cannot read play queue from file May 25 02:24:25 motivo volumio[1283]: info: Network Backup Found, restore started May 25 02:24:25 motivo sudo[2041]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 02:24:25 motivo sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2043]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo sudo[2048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /imgpart/networkconfig.json May 25 02:24:25 motivo sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2048]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo volumio[1283]: info: Volumio Network Manager: Network status updated: 1 May 25 02:24:25 motivo volumio[1283]: info: Motivo Hardware found May 25 02:24:25 motivo volumio[1283]: info: MRS: Removed streaming files May 25 02:24:25 motivo volumio[1283]: info: MRS: volumioStreaming STOPPED May 25 02:24:25 motivo volumio[1283]: info: MRS: SNAPSERVER STOPPED May 25 02:24:25 motivo volumio[1283]: info: MRS: SNAPCLIENT STOPPED May 25 02:24:25 motivo volumio[1283]: info: VolumeController:: Volume=46 Mute =false May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::updateTrackBlock May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrackBlock May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioRetrievevolume May 25 02:24:25 motivo volumio[1283]: info: VolumeController:: Volume=46 Mute =false May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::updateTrackBlock May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrackBlock May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioRetrievevolume May 25 02:24:25 motivo volumio[1283]: info: Restoring Network Settings Backup May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting dhcp May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting ethip May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting ethnetmask May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting ethgateway May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting hotspot_name May 25 02:24:25 motivo volumio[1283]: info: Restoring Network May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting enable_hotspot May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting hotspot_fallback May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting hotspot_protection May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting hotspot_password May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting hotspot_channel May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting wireless_enabled May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting wirelessdhcp May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting wirelessip May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting wirelessnetmask May 25 02:24:25 motivo volumio[1283]: info: Restoring Setting wirelessgateway May 25 02:24:25 motivo volumio[1283]: info: Setting Device type: Raspberry PI May 25 02:24:25 motivo volumio[1283]: info: VolumeController:: Volume=46 Mute =false May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:25 motivo volumio[1283]: info: VolumeController:: Volume=46 Mute =false May 25 02:24:25 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:25 motivo volumio[1283]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 25 02:24:25 motivo volumio[1283]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03141 May 25 02:24:25 motivo volumio[1283]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 25 02:24:25 motivo volumio[1283]: info: Discovery: A device disappeared from network May 25 02:24:25 motivo volumio[1283]: info: Completed loading Core Plugins May 25 02:24:25 motivo volumio[1283]: info: Preparing to generate the ALSA configuration file May 25 02:24:25 motivo sudo[2067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 02:24:25 motivo sudo[2067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo volumio[1283]: info: Discovery: A device disappeared from network May 25 02:24:25 motivo volumio[1283]: info: Discovery: adding d583e1bc-f833-42e7-8dcd-7a39367433fc May 25 02:24:25 motivo volumio[1283]: info: Discovery: Found device Motivo May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: MRS: Pushing multiroomSync output for this device May 25 02:24:25 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:25 motivo volumio[1283]: info: Adding audio output: May 25 02:24:25 motivo volumio[1283]: info: Adding audio output: May 25 02:24:25 motivo volumio[1283]: info: Discovery: this is already registered, d583e1bc-f833-42e7-8dcd-7a39367433fc May 25 02:24:25 motivo volumio[1283]: info: Discovery: Found device Motivo May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:25 motivo volumio[1283]: info: Asound.conf file written May 25 02:24:25 motivo sudo[2085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 02:24:25 motivo sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2085]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo volumio[1283]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 02:24:25 motivo volumio[1283]: No state is present for card IQAudIODigi May 25 02:24:25 motivo volumio[1283]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 02:24:25 motivo volumio[1283]: Found hardware: "RPi-WM8804" "" "" "" "" May 25 02:24:25 motivo volumio[1283]: Hardware is initialized using a generic method May 25 02:24:25 motivo volumio[1283]: No state is present for card IQAudIODigi May 25 02:24:25 motivo volumio[1283]: No state is present for card Amanero May 25 02:24:25 motivo volumio[1283]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a23" "" "" May 25 02:24:25 motivo volumio[1283]: Hardware is initialized using a generic method May 25 02:24:25 motivo volumio[1283]: No state is present for card Amanero May 25 02:24:25 motivo volumio[1283]: info: Output device has changed, restarting MPD May 25 02:24:25 motivo volumio[1283]: info: Output device has changed, restarting Shairport Sync May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:25 motivo sudo[2091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 02:24:25 motivo sudo[2091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 02:24:25 motivo sudo[2091]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo sudo[2093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo volumio[1283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 02:24:25 motivo volumio[1283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: ___________ START PLUGINS ___________ May 25 02:24:25 motivo volumio[1283]: info: Setting Hotspot Unique name on first start: Motivo-9EB1F May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::Reload Ui May 25 02:24:25 motivo volumio[1283]: info: ControllerMpd::onStart: Initializing MPD May 25 02:24:25 motivo volumio[1283]: info: Creating MPD Configuration file May 25 02:24:25 motivo sudo[2104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 25 02:24:25 motivo sudo[2104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 02:24:25 motivo sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 02:24:25 motivo systemd[1]: Starting mpd.service - Music Player Daemon... May 25 02:24:25 motivo sudo[2106]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 02:24:25 motivo volumio[1283]: info: [1779675865942] CoreMusicLibrary::Adding element Media Servers May 25 02:24:25 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:24:25 motivo volumio[1283]: info: UPNP Browser: Client initialized successfully May 25 02:24:25 motivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 02:24:25 motivo sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 02:24:25 motivo sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:25 motivo sudo[2104]: pam_unix(sudo:session): session closed for user root May 25 02:24:25 motivo volumio[1283]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 25 02:24:25 motivo volumio[1283]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 25 02:24:25 motivo volumio[1283]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 25 02:24:25 motivo volumio[1283]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 25 02:24:25 motivo volumio[1283]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 25 02:24:26 motivo systemd[1]: mpd.service: Deactivated successfully. May 25 02:24:26 motivo systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 02:24:26 motivo systemd[1]: mpd.socket: Deactivated successfully. May 25 02:24:26 motivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 02:24:26 motivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 02:24:26 motivo volumio[1283]: info: Adding METAVOLUMIO REST API Endpoints May 25 02:24:26 motivo volumio[1283]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 25 02:24:26 motivo volumio[1283]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 25 02:24:26 motivo volumio[1283]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 25 02:24:26 motivo volumio[1283]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 25 02:24:26 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:26 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:26 motivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 02:24:26 motivo volumio[1283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 02:24:26 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:26 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:26 motivo systemd[1]: Starting mpd.service - Music Player Daemon... May 25 02:24:26 motivo bluetoothd[940]: Path / reserved for Adv Monitor app :1.18 May 25 02:24:26 motivo bluetoothd[940]: Adv Monitor app :1.18 disconnected from D-Bus May 25 02:24:26 motivo volumio[1283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 02:24:26 motivo volumio[1283]: info: Preparing CD Folders May 25 02:24:26 motivo volumio[1283]: info: Adding CD REST API Endpoints May 25 02:24:26 motivo volumio[1283]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 25 02:24:26 motivo volumio[1283]: info: Starting UDEV Watcher for CD May 25 02:24:26 motivo volumio[1283]: info: Detecting CD presence with UDEV May 25 02:24:26 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 25 02:24:26 motivo sudo[2121]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 02:24:26 motivo sudo[2121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 02:24:26 motivo sudo[2134]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 02:24:26 motivo sudo[2121]: pam_unix(sudo:session): session closed for user root May 25 02:24:26 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:26.297Z level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z May 25 02:24:26 motivo nmbd[1160]: [2026/05/25 02:24:26.756645, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 25 02:24:26 motivo nmbd[1160]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.5 for name WORKGROUP<1d>. May 25 02:24:26 motivo nmbd[1160]: This response was from IP 192.168.0.1, reporting an IP address of 192.168.0.1. May 25 02:24:28 motivo mpd[2135]: 2026-05-25T02:24:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 02:24:28 motivo mpd[2135]: 2026-05-25T02:24:28 exception: Database corrupted May 25 02:24:28 motivo systemd[1]: Started mpd.service - Music Player Daemon. May 25 02:24:28 motivo sudo[2093]: pam_unix(sudo:session): session closed for user root May 25 02:24:28 motivo sudo[2111]: pam_unix(sudo:session): session closed for user root May 25 02:24:28 motivo systemd[1]: systemd-fsckd.service: Deactivated successfully. May 25 02:24:28 motivo volumio-remote-updater[887]: [2026-05-25 02:24:28] [connect] Successful connection May 25 02:24:31 motivo volumio[1283]: warn: [cd-plugin] cdspeedctl: device or media not ready May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 02:24:31 motivo volumio[1283]: info: [1779675871145] CoreMusicLibrary::Adding element Last_100 May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:24:31 motivo volumio[1283]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 25 02:24:31 motivo volumio[1283]: info: QobuzConnect: Starting Qobuz Connect socket and service May 25 02:24:31 motivo volumio[1283]: info: Starting RAAT Plugin May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 25 02:24:31 motivo volumio[1283]: info: Additional UI Settings Added for plugin music_service/raat May 25 02:24:31 motivo volumio[1283]: info: Registering DSP Elements listener and retrieving current ones May 25 02:24:31 motivo volumio[1283]: info: Additional DSP elements updated May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:31 motivo volumio[1283]: info: Updating RAAT Signal Path May 25 02:24:31 motivo sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 02:24:31 motivo volumio[1283]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') May 25 02:24:31 motivo sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo volumio[1283]: info: Streaming services startup May 25 02:24:31 motivo volumio[1283]: info: Starting Streaming Daemon May 25 02:24:31 motivo sudo[2183]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo sudo[2188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 25 02:24:31 motivo sudo[2188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo sudo[2191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 02:24:31 motivo sudo[2191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 02:24:31 motivo volumio[1283]: info: [1779675871250] CoreMusicLibrary::Adding element Webradio May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 02:24:31 motivo sudo[2188]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo volumio[1283]: info: Initializing BBC Radios May 25 02:24:31 motivo sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 02:24:31 motivo sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 25 02:24:31 motivo sudo[2191]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 25 02:24:31 motivo sudo[2197]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo volumio[1283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:31 motivo volumio[1283]: error: Hi Res Audio Failed Login: Missing Login Data May 25 02:24:31 motivo volumio[1283]: info: Adding HIGHRESAUDIO REST API Endpoints May 25 02:24:31 motivo volumio[1283]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 25 02:24:31 motivo volumio[1283]: info: Initializing Serial Communication on port /dev/ttyAMA4 May 25 02:24:31 motivo volumio[1283]: info: Touch Event Listener Process Starting May 25 02:24:31 motivo volumio[1283]: info: Adding inputs REST Endpoints May 25 02:24:31 motivo volumio[1283]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 25 02:24:31 motivo volumio[1283]: info: Scanning Audio Inputs May 25 02:24:31 motivo sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root May 25 02:24:31 motivo sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo volumio[1283]: info: Checking against Known Cards name May 25 02:24:31 motivo volumio[1283]: info: Checking against Known Cards name May 25 02:24:31 motivo volumio[1283]: info: Adding Server instance for streaming May 25 02:24:31 motivo volumio[1283]: info: Volumio Calling Home May 25 02:24:31 motivo volumio[1283]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 25 02:24:31 motivo volumio[1283]: (node:1283) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 25 02:24:31 motivo volumio[1283]: (Use `node --trace-deprecation ...` to show where the warning was created) May 25 02:24:31 motivo volumio[1283]: info: Stopping AccessToken refresher cron for QOBUZ May 25 02:24:31 motivo volumio[1283]: info: AccessToken refresher cron started for QOBUZ May 25 02:24:31 motivo volumio[1283]: info: Adding TIDAL REST API Endpoints May 25 02:24:31 motivo volumio[1283]: info: Adding QOBUZ REST API Endpoints May 25 02:24:31 motivo sudo[2228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf May 25 02:24:31 motivo sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf May 25 02:24:31 motivo sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo sudo[2232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 25 02:24:31 motivo sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo volumio[1283]: info: Serial port opened successfully May 25 02:24:31 motivo volumio[1283]: info: Sending serial start messages May 25 02:24:31 motivo volumio[1283]: info: Display with brightness control is connected, setting display brightness May 25 02:24:31 motivo volumio[1283]: info: Setting Display Brightness to 12 May 25 02:24:31 motivo sudo[2232]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo sudo[2230]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo sudo[2228]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:31 motivo volumio[1283]: info: Reporting MCU Network Status: 1 May 25 02:24:31 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 25 02:24:31 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 02:24:31 motivo volumio[1283]: info: Discovery: this is already registered, d583e1bc-f833-42e7-8dcd-7a39367433fc May 25 02:24:31 motivo volumio[1283]: info: Discovery: Found device Motivo May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:31 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:31 motivo volumio[1283]: info: Discovery: this is already registered, d583e1bc-f833-42e7-8dcd-7a39367433fc May 25 02:24:31 motivo volumio[1283]: info: Discovery: Found device Motivo May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:31 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:31 motivo volumio[1283]: error: Cannot start Volumio Streaming Daemon May 25 02:24:31 motivo volumio[1283]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 25 02:24:31 motivo volumio[1283]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 25 02:24:31 motivo volumio[1283]: info: RAAT Albumart path created successfully May 25 02:24:31 motivo volumio[1283]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 25 02:24:31 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 02:24:31 motivo sudo[2067]: pam_unix(sudo:session): session closed for user root May 25 02:24:31 motivo volumio[1283]: info: MPD Permissions set May 25 02:24:31 motivo volumio[1283]: info: MPD Permissions set May 25 02:24:31 motivo sudo[2244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 25 02:24:31 motivo sudo[2244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:31 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 25 02:24:31 motivo volumio[1283]: info: Setting Device Volume Override May 25 02:24:32 motivo volumio[1283]: info: Applying Volume Override May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 25 02:24:32 motivo volumio[1283]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 25 02:24:32 motivo volumio[1283]: info: Enabling external Volume Control May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:32 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:32 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:32 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:32 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:32 motivo systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 25 02:24:32 motivo volumio[1283]: 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 May 25 02:24:32 motivo sudo[2244]: pam_unix(sudo:session): session closed for user root May 25 02:24:32 motivo volumiobt[2273]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 25 02:24:32 motivo volumio[1283]: info: Upmpdcli Daemon Started May 25 02:24:32 motivo volumio[1283]: info: Permissions for /etc/hostapd/hostapd.conf May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 25 02:24:32 motivo volumio[1283]: info: Permissions for /etc/hostapd/hostapd.conf May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 25 02:24:32 motivo sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 25 02:24:32 motivo sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo sudo[2276]: pam_unix(sudo:session): session closed for user root May 25 02:24:32 motivo sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 25 02:24:32 motivo sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo sudo[2282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 25 02:24:32 motivo sudo[2282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo sudo[2282]: pam_unix(sudo:session): session closed for user root May 25 02:24:32 motivo sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 25 02:24:32 motivo sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo volumiobt[2286]: INFO [BTSTART] Powering on Bluetooth if needed... May 25 02:24:32 motivo volumio[1283]: 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 May 25 02:24:32 motivo volumio[1283]: info: Received Get System Info May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:24:32 motivo volumio[1283]: info: Discovery: Getting this device information May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:32 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:24:32 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:32.220Z level=INFO msg="system info for 9eb1f6ee4207a0ff32a605a3683ce03b" deviceName=Motivo deviceVariant=motivo deviceModel="Volumio Motivo" softwareVersion=4.156 May 25 02:24:32 motivo systemd[1]: Stopping wireless.service - Wireless Services... May 25 02:24:32 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:32.235Z level=INFO msg="bootstrapping state" hasInternet=true May 25 02:24:32 motivo systemd[1]: wireless.service: Killing process 1642 (wpa_supplicant) with signal SIGKILL. May 25 02:24:32 motivo bluetoothd[940]: Adv Monitor app :1.22 disconnected from D-Bus May 25 02:24:32 motivo volumiobt[2290]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 25 02:24:32 motivo volumio[1283]: error: MPD error: The expression evaluated to a falsy value: May 25 02:24:32 motivo volumio[1283]: assert.ok(self.idling) May 25 02:24:32 motivo volumio[1283]: error: The expression evaluated to a falsy value: May 25 02:24:32 motivo volumio[1283]: assert.ok(self.idling) May 25 02:24:32 motivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 25 02:24:32 motivo systemd[1]: wireless.service: Deactivated successfully. May 25 02:24:32 motivo systemd[1]: Stopped wireless.service - Wireless Services. May 25 02:24:32 motivo systemd[1]: wireless.service: Consumed 3.437s CPU time. May 25 02:24:32 motivo volumiobt[2291]: [176B blob data] May 25 02:24:32 motivo volumiobt[2291]: [157B blob data] May 25 02:24:32 motivo volumiobt[2291]: [157B blob data] May 25 02:24:32 motivo volumiobt[2291]: [157B blob data] May 25 02:24:32 motivo volumiobt[2291]: [113B blob data] May 25 02:24:32 motivo volumiobt[2291]: [bluetoothctl]> discoverable on May 25 02:24:32 motivo volumiobt[2291]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended May 25 02:24:32 motivo volumiobt[2291]: [bluetoothctl]> pairable on May 25 02:24:32 motivo volumio[1283]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 25 02:24:32 motivo volumio[1283]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 25 02:24:32 motivo volumio[1283]: info: VolumeController:: alsactl monitor closed, code: 0 May 25 02:24:32 motivo volumio[1283]: info: MPD running with PID2135 May 25 02:24:32 motivo volumio[1283]: ,establishing connection May 25 02:24:32 motivo bluetoothd[940]: Path / reserved for Adv Monitor app :1.23 May 25 02:24:32 motivo bluetoothd[940]: Adv Monitor app :1.23 disconnected from D-Bus May 25 02:24:32 motivo volumiobt[2291]: [bluetoothctl]> May 25 02:24:32 motivo volumiobt[2295]: INFO [BTSTART] Registering Bluetooth agent... May 25 02:24:32 motivo systemd[1]: Starting wireless.service - Wireless Services... May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumiobt[2296]: [NEW] Media /org/bluez/hci0 May 25 02:24:32 motivo volumiobt[2296]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo volumiobt[2296]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo volumiobt[2296]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo bluetoothd[940]: Adv Monitor app :1.24 disconnected from D-Bus May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumiobt[2310]: No agent is registered May 25 02:24:32 motivo volumiobt[2310]: [NEW] Media /org/bluez/hci0 May 25 02:24:32 motivo volumiobt[2310]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo volumiobt[2310]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo volumiobt[2310]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 25 02:24:32 motivo volumio[1283]: info: Received Get System Info May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:24:32 motivo volumio[1283]: info: Discovery: Getting this device information May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:32 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:24:32 motivo bluetoothd[940]: Adv Monitor app :1.25 disconnected from D-Bus May 25 02:24:32 motivo volumiobt[2317]: INFO [BTSTART] Agent registered successfully. May 25 02:24:32 motivo volumiobt[2319]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 25 02:24:32 motivo volumio[1283]: error: updateQueue error: null May 25 02:24:32 motivo volumio-remote-updater[887]: [2026-05-25 02:24:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779675868 101 May 25 02:24:32 motivo volumio[1283]: 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 May 25 02:24:32 motivo volumio[1283]: info: Volumio called home May 25 02:24:32 motivo volumio-remote-updater[887]: Test mode disabled May 25 02:24:32 motivo volumio-remote-updater[887]: Alpha mode disabled May 25 02:24:32 motivo volumio-remote-updater[887]: Alpha legacy test mode disabled May 25 02:24:32 motivo volumio[1283]: info: Executing endpoint qc_getconfig May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.727 [2202.2202] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 25 02:24:32 motivo volumio[1283]: error: updateQueue error: null May 25 02:24:32 motivo sudo[2327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 25 02:24:32 motivo volumio[1283]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 25 02:24:32 motivo sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo volumio[1283]: info: QobuzConnect: QOBUZ Connect daemon connected May 25 02:24:32 motivo volumio[1283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 25 02:24:32 motivo volumio[1283]: info: Starting Shairport Sync May 25 02:24:32 motivo volumio[1283]: info: Starting Shairport Sync May 25 02:24:32 motivo volumio[1283]: info: Starting Shairport Sync May 25 02:24:32 motivo systemd[1]: wireless.service: Deactivated successfully. May 25 02:24:32 motivo systemd[1]: Stopped wireless.service - Wireless Services. May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 25 02:24:32 motivo volumio[1283]: info: Setting Additional System Software info: Hardware Revision: 2.4 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 25 02:24:32 motivo volumio[1283]: info: Setting HW Firmware info: undefined May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 25 02:24:32 motivo volumio[1283]: info: Setting HW Version info: 2.4 May 25 02:24:32 motivo sudo[2333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 02:24:32 motivo sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 25 02:24:32 motivo volumio[1283]: info: Setting Additional System Software info: Hardware Revision: 2.4, Firmware Version: 0.2.0 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 25 02:24:32 motivo volumio[1283]: info: Setting HW Firmware info: 0.2.0 May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 25 02:24:32 motivo volumio[1283]: info: Setting HW Version info: 2.4 May 25 02:24:32 motivo volumio[1283]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 25 02:24:32 motivo volumio[1283]: info: MCU Signalled Headphone Mode Disabled May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 25 02:24:32 motivo sudo[2331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 02:24:32 motivo volumio[1283]: info: Reporting Headphone State: false May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:32 motivo volumio[1283]: info: Updating RAAT Signal Path May 25 02:24:32 motivo volumio[1283]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') May 25 02:24:32 motivo sudo[2331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo sudo[2335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 02:24:32 motivo systemd[1]: Starting wireless.service - Wireless Services... May 25 02:24:32 motivo volumio[1283]: info: MCU Signalled Sleep Mode Disabled May 25 02:24:32 motivo sudo[2335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.887 [2202.2202] INFO VolumeManager: [0x13e99b8]: Setting new playback volume: 75 May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.887 [2202.2202] INFO VolumeManager: [0x13e99b8]: Setting new mute state: 0 May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.887 [2202.2202] INFO AudioStreamManager: [0x13e9710]: Setting new audio download buffer size: 1048576 May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.887 [2202.2202] INFO QobuzConnect: [0x13ea280]: Client initialized! May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.887 [2202.2202] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp May 25 02:24:32 motivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 02:24:32 motivo systemd[1]: shairport-sync.service: Deactivated successfully. May 25 02:24:32 motivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:32 motivo systemd[1]: shairport-sync.service: Consumed 1.902s CPU time. May 25 02:24:32 motivo volumio[1283]: info: Enabling Advanced system settings configuration May 25 02:24:32 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections May 25 02:24:32 motivo volumio[1283]: info: Additional UI Settings Added for plugin music_service/inputs May 25 02:24:32 motivo volumio[1283]: info: MCU Signalled Auto Boot Mode On Power Disabled May 25 02:24:32 motivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:32 motivo sudo[2333]: pam_unix(sudo:session): session closed for user root May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.934 [2202.2202] INFO LocalConfigManager: [0x13e9438]: Starting Local Configuration server May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.934 [2202.2202] INFO SampleApp: Starting Local configuration server May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.935 [2202.2202] INFO SampleApp: Connected to UNIX socket client 0x13d4818 May 25 02:24:32 motivo qobuz-connect[2202]: 20260525 02:24:32.935 [2202.2202] INFO SampleApp: Playback volume changed: 75 May 25 02:24:32 motivo volumio[1283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 25 02:24:32 motivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 02:24:32 motivo sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on May 25 02:24:32 motivo sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:32 motivo systemd[1]: shairport-sync.service: Deactivated successfully. May 25 02:24:32 motivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:32 motivo sudo[2343]: pam_unix(sudo:session): session closed for user root May 25 02:24:32 motivo volumio[1283]: info: Preparing to generate the ALSA configuration file May 25 02:24:32 motivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:32 motivo sudo[2331]: pam_unix(sudo:session): session closed for user root May 25 02:24:33 motivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 02:24:33 motivo systemd[1]: shairport-sync.service: Deactivated successfully. May 25 02:24:33 motivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:33 motivo volumio[1283]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 02:24:33 motivo volumio[1283]: info: Reading ALSA contributions from plugins. May 25 02:24:33 motivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo sudo[2335]: pam_unix(sudo:session): session closed for user root May 25 02:24:33 motivo volumio[1283]: info: Shairport-Sync Started May 25 02:24:33 motivo volumio[1283]: Error adding Membership: Error: addMembership EINVAL May 25 02:24:33 motivo volumio[1283]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 25 02:24:33 motivo volumio[1283]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:33 motivo volumio[1283]: info: Shairport-Sync Started May 25 02:24:33 motivo volumio[1283]: info: Shairport-Sync Started May 25 02:24:33 motivo volumio[1283]: info: Adding Inputs via Serial API May 25 02:24:33 motivo sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 May 25 02:24:33 motivo sudo[2374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:33 motivo sudo[2374]: pam_unix(sudo:session): session closed for user root May 25 02:24:33 motivo volumio[1283]: info: Adding Advanced Audio Settings via Serial API May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 25 02:24:33 motivo volumio[1283]: info: Additional UI Settings Added for plugin music_service/inputs May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::servicePushState May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} May 25 02:24:33 motivo volumio[1283]: verbose: CURRENT POSITION 0 May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::syncState stateService stop May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::syncState currentStatus stop May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:33 motivo volumio[1283]: info: No code May 25 02:24:33 motivo volumio[1283]: info: CoreStateMachine::pushState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioPushState May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output update for this device May 25 02:24:33 motivo volumio[1283]: info: MRS: Pushing multiroomSync output May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:24:33 motivo volumio[1283]: info: Adding Advanced Audio Settings via Serial API May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 25 02:24:33 motivo volumio[1283]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: === SNM TRANSITION === May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: Previous ethernet state: disconnected May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: New ethernet state: connected May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: Single Network Mode: enabled May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: First start: yes May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: === END TRANSITION === May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 02:24:33 motivo wireless.js[2338]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 02:24:33 motivo volumio[1283]: info: MCU Signalled Playback Inactive May 25 02:24:33 motivo volumio[1283]: info: Received Get System Info May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:24:33 motivo volumio[1283]: info: Discovery: Getting this device information May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:33 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 02:24:33 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 02:24:33 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:33.954Z level=INFO msg="enabling local network discovery" May 25 02:24:33 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:33.989Z level=INFO msg="enabling BLE discovery" May 25 02:24:33 motivo volumio[1283]: info: Asound.conf file written May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Connecting to system D-Bus May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Connected to system D-Bus May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 bluezutils [INFO] Found adapter at: /org/bluez/hci0 May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Set DiscoverableTimeout to infinite May 25 02:24:34 motivo sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 02:24:34 motivo sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Enabled Discoverable mode May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Agent registered at /local/a2dpagent May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] Agent set as default May 25 02:24:34 motivo volumiobt[2320]: 2026-05-25 02:24:34 a2dp-agent [INFO] A2DP agent running, waiting for connections... May 25 02:24:34 motivo sudo[2406]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo volumio[1283]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 02:24:34 motivo volumio[1283]: No state is present for card IQAudIODigi May 25 02:24:34 motivo volumio[1283]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 02:24:34 motivo volumio[1283]: Found hardware: "RPi-WM8804" "" "" "" "" May 25 02:24:34 motivo volumio[1283]: Hardware is initialized using a generic method May 25 02:24:34 motivo volumio[1283]: No state is present for card IQAudIODigi May 25 02:24:34 motivo volumio[1283]: No state is present for card Amanero May 25 02:24:34 motivo volumio[1283]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a23" "" "" May 25 02:24:34 motivo volumio[1283]: Hardware is initialized using a generic method May 25 02:24:34 motivo volumio[1283]: No state is present for card Amanero May 25 02:24:34 motivo volumio[1283]: info: Output device has changed, restarting MPD May 25 02:24:34 motivo volumio[1283]: info: Output device has changed, restarting Shairport Sync May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:34 motivo sudo[2437]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 02:24:34 motivo sudo[2437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 02:24:34 motivo sudo[2437]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 02:24:34 motivo sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo sudo[2440]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 02:24:34 motivo sudo[2440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 02:24:34 motivo sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 02:24:34 motivo sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 02:24:34 motivo sudo[2432]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo volumio[1283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 02:24:34 motivo volumio[1283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: QobuzConnect: setDeactiveState invoked May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:34 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:34 motivo volumio[1283]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: Preparing to generate the ALSA configuration file May 25 02:24:34 motivo volumio[1283]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 02:24:34 motivo volumio[1283]: info: Reading ALSA contributions from plugins. May 25 02:24:34 motivo volumio[1283]: info: Initializing I2S Bus May 25 02:24:34 motivo volumio[1283]: info: MPD Permissions set May 25 02:24:34 motivo volumio[1283]: info: TidalConnect service stoped! May 25 02:24:34 motivo systemd[1]: mpd.service: Deactivated successfully. May 25 02:24:34 motivo systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 02:24:34 motivo systemd[1]: mpd.service: Consumed 2.313s CPU time. May 25 02:24:34 motivo systemd[1]: mpd.socket: Deactivated successfully. May 25 02:24:34 motivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 02:24:34 motivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo systemd[1]: Starting mpd.service - Music Player Daemon... May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 25 02:24:34 motivo volumio[1283]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 25 02:24:34 motivo volumio[1283]: info: Starting Shairport Sync May 25 02:24:34 motivo volumio[1283]: info: Asound.conf file unchanged, so no further update is needed May 25 02:24:34 motivo volumio[1283]: info: Output device has changed, restarting MPD May 25 02:24:34 motivo volumio[1283]: info: Output device has changed, restarting Shairport Sync May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 02:24:34 motivo volumio[1283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 02:24:34 motivo volumio[1283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo sudo[2440]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo wireless.js[2338]: WIRELESS.JS - INFO: Cleaning previous... May 25 02:24:34 motivo volumio[1283]: info: QobuzConnect: setDeactiveState invoked May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::volumioGetState May 25 02:24:34 motivo volumio[1283]: info: CorePlayQueue::getTrack 0 May 25 02:24:34 motivo volumio[1283]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 25 02:24:34 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:34 motivo wireless.js[2338]: WIRELESS.JS - INFO: Wireless Networking DISABLED, not starting wireless flow May 25 02:24:34 motivo sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 02:24:34 motivo sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo sudo[2465]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 02:24:34 motivo sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 02:24:34 motivo sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 02:24:34 motivo sudo[2484]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo sudo[2465]: pam_unix(sudo:session): session closed for user root May 25 02:24:34 motivo sudo[2476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 25 02:24:34 motivo wireless.js[2338]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 25 02:24:34 motivo sudo[2486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 02:24:34 motivo sudo[2503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 02:24:34 motivo sudo[2503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 02:24:34 motivo sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo systemd[1]: Started wireless.service - Wireless Services. May 25 02:24:34 motivo sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:34 motivo sudo[2486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:35 motivo sudo[2280]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo sudo[2327]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo sudo[2278]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo sudo[2512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 25 02:24:35 motivo sudo[2512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:35 motivo volumio5-onboarding[2112]: time=2026-05-25T02:24:35.048Z level=INFO msg="service successfully established" component=discovery/localnet May 25 02:24:35 motivo sudo[2520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo sudo[2453]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo sudo[2520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo sudo[2503]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 02:24:35 motivo volumio[1283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 02:24:35 motivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 02:24:35 motivo sudo[2476]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo systemd[1]: shairport-sync.service: Deactivated successfully. May 25 02:24:35 motivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:35 motivo systemd[1]: shairport-sync.service: Consumed 1.221s CPU time. May 25 02:24:35 motivo volumio[1283]: info: Not Reporting Auto name since its the default one May 25 02:24:35 motivo systemd[1]: mpd.service: Deactivated successfully. May 25 02:24:35 motivo systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 02:24:35 motivo sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 02:24:35 motivo sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:35 motivo systemd[1]: mpd.socket: Deactivated successfully. May 25 02:24:35 motivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 02:24:35 motivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 02:24:35 motivo sudo[2520]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo sudo[2512]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo volumio[1283]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 02:24:35 motivo volumio[1283]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 May 25 02:24:35 motivo volumio[1283]: Job for vtcs.service canceled. May 25 02:24:35 motivo volumio[1283]: at ChildProcess.exithandler (node:child_process:421:12) May 25 02:24:35 motivo volumio[1283]: at ChildProcess.emit (node:events:514:28) May 25 02:24:35 motivo volumio[1283]: at maybeClose (node:internal/child_process:1105:16) May 25 02:24:35 motivo volumio[1283]: at Socket. (node:internal/child_process:457:11) May 25 02:24:35 motivo volumio[1283]: at Socket.emit (node:events:514:28) May 25 02:24:35 motivo volumio[1283]: at Pipe. (node:net:337:12) { May 25 02:24:35 motivo volumio[1283]: code: 1, May 25 02:24:35 motivo volumio[1283]: killed: false, May 25 02:24:35 motivo volumio[1283]: signal: null, May 25 02:24:35 motivo volumio[1283]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', May 25 02:24:35 motivo volumio[1283]: stdout: '', May 25 02:24:35 motivo volumio[1283]: stderr: 'Job for vtcs.service canceled.\n' May 25 02:24:35 motivo volumio[1283]: } May 25 02:24:35 motivo volumio[1283]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 02:24:35 motivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 02:24:35 motivo sudo[2532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 25 02:24:35 motivo sudo[2532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 02:24:35 motivo systemd[1]: Starting mpd.service - Music Player Daemon... May 25 02:24:35 motivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 02:24:35 motivo sudo[2482]: pam_unix(sudo:session): session closed for user root May 25 02:24:35 motivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 25 02:24:35 motivo qobuz-connect[2202]: 20260525 02:24:35.378 [2202.2202] INFO SampleApp: Stopping Local configuration server May 25 02:24:35 motivo sudo[2537]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 02:24:35 motivo sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 02:24:35 motivo sudo[2537]: pam_unix(sudo:session): session closed for user root May 25 02:24:36 motivo sudo[2576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 02:23' May 25 02:24:36 motivo sudo[2576]: 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="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="e94c5b27ab2607d3918cd6a4973c240c1b2ce37e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 16 00:11:09 UTC 2026" VOLUMIO_VERSION="4.156" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="67ebfb15187d4de64266b21b13da5443"