-- Logs begin at Thu 2026-04-02 23:25:04 CEST, end at Thu 2026-04-02 23:43:54 CEST. -- Apr 02 23:42:01 rivoplus volumio[3332]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/rivoplus/buster/armhf Apr 02 23:42:01 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:01.881+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:03 rivoplus volumio[3332]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/rivoplus/buster/armhf Apr 02 23:42:03 rivoplus volumio[3332]: info: Folder /tmp/plugins removed Apr 02 23:42:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:03.744+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:04 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:04.795+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:04 rivoplus volumio[3332]: info: Check plugin dependencies Apr 02 23:42:04 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 23:42:04 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:04.933+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:04 rivoplus volumio[3332]: info: Checking if plugin already exists Apr 02 23:42:04 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:04.940+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:04 rivoplus volumio[3332]: info: Rename folder Apr 02 23:42:04 rivoplus volumio[3332]: info: Folder /tmp/downloaded_plugin.zip removed Apr 02 23:42:04 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:04.992+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:04 rivoplus volumio[3332]: info: Move to category Apr 02 23:42:05 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:05.465+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:05 rivoplus volumio[3332]: info: Checking if install.sh is present Apr 02 23:42:05 rivoplus volumio[3332]: info: Executing install.sh Apr 02 23:42:05 rivoplus sudo[6324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Apr 02 23:42:05 rivoplus sudo[6324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:42:05 rivoplus volumio[3332]: info: Installing Go-librespot Apr 02 23:42:05 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:05.624+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:05 rivoplus volumio[3332]: info: Checking old vollibrespot installs Apr 02 23:42:05 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:05.637+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:05 rivoplus systemd[1]: Reloading. Apr 02 23:42:06 rivoplus systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 02 23:42:06 rivoplus systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 02 23:42:06 rivoplus volumio[3332]: info: Downloading daemon Apr 02 23:42:06 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:06.914+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:09 rivoplus volumio[3332]: info: Creating directories Apr 02 23:42:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:09.972+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:09 rivoplus volumio[3332]: info: Creating data directory Apr 02 23:42:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:09.977+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:09 rivoplus volumio[3332]: info: Creating Start Script Apr 02 23:42:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:09.993+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:10 rivoplus systemd[1]: Reloading. Apr 02 23:42:10 rivoplus systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 02 23:42:11 rivoplus sudo[6324]: pam_unix(sudo:session): session closed for user root Apr 02 23:42:11 rivoplus volumio[3332]: info: Install script completed Apr 02 23:42:11 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:11.212+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:11 rivoplus volumio[3332]: info: Adding reference to registry Apr 02 23:42:11 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:11.223+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:11 rivoplus volumio[3332]: info: Done installing plugin. Apr 02 23:42:11 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 23:42:11 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 02 23:42:11 rivoplus volumio[3332]: Plugin install end detected on script Apr 02 23:42:11 rivoplus volumio[3332]: info: Folder /tmp/plugins removed Apr 02 23:42:11 rivoplus volumio[3332]: info: Folder /tmp/downloaded_plugin.zip removed Apr 02 23:42:11 rivoplus volumio[3332]: info: Folder /data/temp removed Apr 02 23:42:13 rivoplus volumio[3332]: info: Enabling plugin spop Apr 02 23:42:13 rivoplus volumio[3332]: info: Loading plugin "spop"... Apr 02 23:42:14 rivoplus volumio[3332]: info: PLUGIN START: spop Apr 02 23:42:14 rivoplus volumio[3332]: info: Creating Spotify config file Apr 02 23:42:14 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:42:14 rivoplus volumio[3332]: info: Done. Apr 02 23:42:14 rivoplus volumio[3332]: info: Spotify config file written Apr 02 23:42:14 rivoplus volumio[3332]: info: No need to fix Spotify hosts Apr 02 23:42:14 rivoplus sudo[6434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 02 23:42:14 rivoplus sudo[6434]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:42:14 rivoplus systemd[1]: Started go-librespot Daemon. Apr 02 23:42:14 rivoplus sudo[6434]: pam_unix(sudo:session): session closed for user root Apr 02 23:42:14 rivoplus go-librespot[6437]: go-librespot daemon starting... Apr 02 23:42:14 rivoplus go-librespot[6437]: time="2026-04-02T23:42:14+02:00" level=info msg="running go-librespot 0.7.1" Apr 02 23:42:14 rivoplus go-librespot[6437]: time="2026-04-02T23:42:14+02:00" level=debug msg="no app state found" Apr 02 23:42:14 rivoplus go-librespot[6437]: time="2026-04-02T23:42:14+02:00" level=debug msg="stored credentials not found" Apr 02 23:42:14 rivoplus go-librespot[6437]: time="2026-04-02T23:42:14+02:00" level=info msg="generated new device id: b6dd508351f4514a66dcb5eb99a1563e6e18ec10" Apr 02 23:42:14 rivoplus go-librespot[6437]: time="2026-04-02T23:42:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 02 23:42:15 rivoplus go-librespot[6437]: time="2026-04-02T23:42:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 02 23:42:15 rivoplus go-librespot[6437]: time="2026-04-02T23:42:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 02 23:42:15 rivoplus go-librespot[6437]: time="2026-04-02T23:42:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 02 23:42:15 rivoplus go-librespot[6437]: time="2026-04-02T23:42:15+02:00" level=info msg="zeroconf server listening on port 35775" Apr 02 23:42:15 rivoplus go-librespot[6437]: time="2026-04-02T23:42:15+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Apr 02 23:42:17 rivoplus volumio[3332]: info: go-librespot daemon successfully initialized Apr 02 23:42:20 rivoplus volumio[3332]: info: Initializing connection to go-librespot Websocket Apr 02 23:42:20 rivoplus go-librespot[6437]: time="2026-04-02T23:42:20+02:00" level=debug msg="new websocket client" Apr 02 23:42:20 rivoplus volumio[3332]: info: Connection to go-librespot Websocket established Apr 02 23:42:23 rivoplus volumio[3332]: info: Getting Spotify volume Apr 02 23:42:23 rivoplus volumio[3332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 02 23:42:23 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:42:30 rivoplus volumio[3332]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Apr 02 23:42:30 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:30.954+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:33 rivoplus volumio[3332]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Apr 02 23:42:33 rivoplus volumio[3332]: info: Folder /tmp/plugins removed Apr 02 23:42:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:33.450+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.068+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: Check plugin dependencies Apr 02 23:42:34 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.141+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: Checking if plugin already exists Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.148+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: Rename folder Apr 02 23:42:34 rivoplus volumio[3332]: info: Folder /tmp/downloaded_plugin.zip removed Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.193+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: Move to category Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.598+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: Checking if install.sh is present Apr 02 23:42:34 rivoplus volumio[3332]: info: Executing install.sh Apr 02 23:42:34 rivoplus sudo[6501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 02 23:42:34 rivoplus sudo[6501]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:42:34 rivoplus volumio[3332]: info: creating filters folder and copying demo filters Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.749+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: copying demo flters Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.812+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/ Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.928+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/ Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.943+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/PEQ/ Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/convfir/ Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/convfir/kef.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/convfir/test.json Apr 02 23:42:34 rivoplus volumio[3332]: info: ./presets/EQ15/ Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.959+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.959+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.959+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.960+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.960+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.961+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.961+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.962+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.962+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:34.962+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio[3332]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.003+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio[3332]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.008+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus sudo[6526]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 02 23:42:35 rivoplus sudo[6526]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:42:35 rivoplus volumio[3332]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.022+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio[3332]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 02 23:42:35 rivoplus volumio[3332]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.030+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.030+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio[3332]: info: Installing/fusiondsp dependencies Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.046+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:35 rivoplus volumio[3332]: info: Get:1 http://archive.debian.org/debian buster InRelease [122 kB] Apr 02 23:42:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:35.330+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:36 rivoplus volumio[3332]: info: Get:2 http://archive.debian.org/debian buster/main armhf Packages [7706 kB] Apr 02 23:42:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:36.104+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:36 rivoplus volumio[3332]: info: Get:3 http://archive.debian.org/debian buster/main Translation-en [5969 kB] Apr 02 23:42:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:36.836+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:37 rivoplus volumio[3332]: info: Get:4 http://archive.debian.org/debian buster/contrib armhf Packages [40.1 kB] Apr 02 23:42:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:37.363+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:37 rivoplus volumio[3332]: info: Get:5 http://archive.debian.org/debian buster/contrib Translation-en [44.2 kB] Apr 02 23:42:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:37.378+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:37 rivoplus volumio[3332]: info: Get:6 http://archive.debian.org/debian buster/non-free armhf Packages [62.1 kB] Apr 02 23:42:37 rivoplus volumio[3332]: info: Get:7 http://archive.debian.org/debian buster/non-free Translation-en [88.9 kB] Apr 02 23:42:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:37.386+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:47 rivoplus volumio[3332]: info: Fetched 14.0 MB in 13s (1113 kB/s) Apr 02 23:42:47 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:47.773+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:52 rivoplus volumio[3332]: info: Reading package lists... Apr 02 23:42:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:52.198+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:53 rivoplus volumio[3332]: info: Building dependency tree... Apr 02 23:42:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:53.031+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:53 rivoplus volumio[3332]: info: Reading state information... Apr 02 23:42:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:53.043+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:53 rivoplus volumio[3332]: info: All packages are up to date. Apr 02 23:42:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:53.118+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:53 rivoplus sudo[6526]: pam_unix(sudo:session): session closed for user root Apr 02 23:42:53 rivoplus sudo[6675]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 02 23:42:53 rivoplus sudo[6675]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:42:53 rivoplus volumio[3332]: info: Reading package lists... Apr 02 23:42:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:53.332+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:54 rivoplus volumio[3332]: info: Building dependency tree... Apr 02 23:42:54 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:54.064+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:54 rivoplus volumio[3332]: info: Reading state information... Apr 02 23:42:54 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:54.075+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: The following additional packages will be installed: Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.336+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: binutils binutils-arm-linux-gnueabihf binutils-common build-essential bzip2 Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.346+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.357+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Apr 02 23:42:55 rivoplus volumio[3332]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libpython3-dev Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.364+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.364+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.400+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-crypto python3-cryptography python3-dbus python3-dev Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-multidict python3-secretstorage python3-setuptools python3-wheel Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-xdg python3-yarl python3.7-dev Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.413+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.414+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.415+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.416+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: Suggested packages: Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.443+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: binutils-doc bzip2-doc debian-keyring gcc-8-doc libstdc++6-8-dbg Apr 02 23:42:55 rivoplus volumio[3332]: info: gcc-multilib autoconf automake libtool flex bison gdb gcc-doc gcc-8-locales Apr 02 23:42:55 rivoplus volumio[3332]: info: libgcc1-dbg libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg Apr 02 23:42:55 rivoplus volumio[3332]: info: liblsan0-dbg libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg Apr 02 23:42:55 rivoplus volumio[3332]: info: libstdc++-8-doc make-doc ed diffutils-doc python-attr-doc python-crypto-doc Apr 02 23:42:55 rivoplus volumio[3332]: info: python-cryptography-doc python3-cryptography-vectors python-dbus-doc Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-dbus-dbg gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 Apr 02 23:42:55 rivoplus volumio[3332]: info: python-secretstorage-doc python-setuptools-doc Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.458+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.459+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.460+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.462+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.462+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.463+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.464+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: The following NEW packages will be installed: Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.518+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: binutils binutils-arm-linux-gnueabihf binutils-common build-essential bzip2 Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.529+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.540+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Apr 02 23:42:55 rivoplus volumio[3332]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libpython3-dev Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.548+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.548+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.567+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-aiohttp python3-asn1crypto python3-async-timeout python3-attr Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-cffi-backend python3-crypto python3-cryptography python3-dbus Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-dev python3-entrypoints python3-gi python3-keyring Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-keyrings.alt python3-multidict python3-pip python3-secretstorage Apr 02 23:42:55 rivoplus volumio[3332]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.580+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.581+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.582+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: 0 upgraded, 49 newly installed, 0 to remove and 0 not upgraded. Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.702+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: Need to get 75.8 MB of archives. Apr 02 23:42:55 rivoplus volumio[3332]: info: After this operation, 165 MB of additional disk space will be used. Apr 02 23:42:55 rivoplus volumio[3332]: info: Get:1 http://archive.debian.org/debian buster/main armhf bzip2 armhf 1.0.6-9.2~deb10u1 [46.7 kB] Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.711+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.713+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: Get:2 http://archive.debian.org/debian buster/main armhf binutils-common armhf 2.31.1-16 [2073 kB] Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.735+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: Get:3 http://archive.debian.org/debian buster/main armhf libbinutils armhf 2.31.1-16 [302 kB] Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.929+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:55 rivoplus volumio[3332]: info: Get:4 http://archive.debian.org/debian buster/main armhf binutils-arm-linux-gnueabihf armhf 2.31.1-16 [2101 kB] Apr 02 23:42:55 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:55.955+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:5 http://archive.debian.org/debian buster/main armhf binutils armhf 2.31.1-16 [56.7 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.153+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:6 http://archive.debian.org/debian buster/main armhf libcc1-0 armhf 8.3.0-6 [38.9 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.166+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:7 http://archive.debian.org/debian buster/main armhf libasan5 armhf 8.3.0-6 [336 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.183+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:8 http://archive.debian.org/debian buster/main armhf libubsan1 armhf 8.3.0-6 [103 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.200+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:9 http://archive.debian.org/debian buster/main armhf libgcc-8-dev armhf 8.3.0-6 [612 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.208+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:56 rivoplus volumio[3332]: info: Get:10 http://archive.debian.org/debian buster/main armhf gcc-8 armhf 8.3.0-6 [7229 kB] Apr 02 23:42:56 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:56.238+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:11 http://archive.debian.org/debian buster/main armhf gcc armhf 4:8.3.0-1 [5204 B] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.138+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:12 http://archive.debian.org/debian buster/main armhf libstdc++-8-dev armhf 8.3.0-6 [1584 kB] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.151+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:13 http://archive.debian.org/debian buster/main armhf g++-8 armhf 8.3.0-6 [7463 kB] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.330+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:14 http://archive.debian.org/debian buster/main armhf g++ armhf 4:8.3.0-1 [1624 B] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.938+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:15 http://archive.debian.org/debian buster/main armhf make armhf 4.2.1-1.2 [327 kB] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.954+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:16 http://archive.debian.org/debian buster/main armhf patch armhf 2.7.6-3+deb10u1 [123 kB] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.971+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:57 rivoplus volumio[3332]: info: Get:17 http://archive.debian.org/debian buster/main armhf dpkg-dev all 1.19.8 [1776 kB] Apr 02 23:42:57 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:57.990+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:18 http://archive.debian.org/debian buster/main armhf build-essential armhf 12.6 [7568 B] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.120+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:19 http://archive.debian.org/debian buster/main armhf dh-python all 3.20190308 [99.3 kB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.138+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:20 http://archive.debian.org/debian buster/main armhf libfakeroot armhf 1.23-1 [41.8 kB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.147+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:21 http://archive.debian.org/debian buster/main armhf fakeroot armhf 1.23-1 [83.8 kB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.169+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:22 http://archive.debian.org/debian buster/main armhf libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.187+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:23 http://archive.debian.org/debian buster/main armhf libalgorithm-diff-xs-perl armhf 0.04-5+b1 [11.3 kB] Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:24 http://archive.debian.org/debian buster/main armhf libalgorithm-merge-perl all 0.08-3 [12.7 kB] Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:25 http://archive.debian.org/debian buster/main armhf libexpat1-dev armhf 2.2.6-2+deb10u4 [127 kB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.204+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.206+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:42:58 rivoplus volumio[3332]: info: Get:26 http://archive.debian.org/debian buster/main armhf libpython3.7-dev armhf 3.7.3-2+deb10u3 [47.2 MB] Apr 02 23:42:58 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:42:58.237+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:02 rivoplus volumio[3332]: info: Get:27 http://archive.debian.org/debian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] Apr 02 23:43:02 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:02.850+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:02 rivoplus volumio[3332]: info: Get:28 http://archive.debian.org/debian buster/main armhf python-pip-whl all 18.1-5 [1591 kB] Apr 02 23:43:02 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:02.876+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:29 http://archive.debian.org/debian buster/main armhf python3-multidict armhf 4.5.2-1 [53.5 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.081+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:30 http://archive.debian.org/debian buster/main armhf python3-yarl armhf 1.3.0-1 [44.0 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.099+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:31 http://archive.debian.org/debian buster/main armhf python3-async-timeout all 3.0.1-1 [6292 B] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.109+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:32 http://archive.debian.org/debian buster/main armhf python3-attr all 18.2.0-1 [37.3 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.126+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:33 http://archive.debian.org/debian buster/main armhf python3-aiohttp armhf 3.5.1-1+deb10u1 [245 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.189+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:34 http://archive.debian.org/debian buster/main armhf python3-asn1crypto all 0.24.0-1 [78.2 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.206+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:35 http://archive.debian.org/debian buster/main armhf python3-cffi-backend armhf 1.12.2-1 [70.2 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.351+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:36 http://archive.debian.org/debian buster/main armhf python3-crypto armhf 2.6.1-9+b1 [257 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.369+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:37 http://archive.debian.org/debian buster/main armhf python3-cryptography armhf 2.6.1-3+deb10u2 [189 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.498+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:38 http://archive.debian.org/debian buster/main armhf python3-dbus armhf 1.2.8-3 [96.3 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.517+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:39 http://archive.debian.org/debian buster/main armhf python3.7-dev armhf 3.7.3-2+deb10u3 [509 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.652+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:40 http://archive.debian.org/debian buster/main armhf python3-dev armhf 3.7.3-1 [1264 B] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.807+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:41 http://archive.debian.org/debian buster/main armhf python3-entrypoints all 0.3-1 [5508 B] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.828+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:42 http://archive.debian.org/debian buster/main armhf python3-gi armhf 3.30.4-1 [163 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.837+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:43 http://archive.debian.org/debian buster/main armhf python3-secretstorage all 2.3.1-2 [14.2 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.856+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:44 http://archive.debian.org/debian buster/main armhf python3-keyring all 17.1.1-1 [43.1 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.864+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:45 http://archive.debian.org/debian buster/main armhf python3-keyrings.alt all 3.1.1-1 [18.2 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.882+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:46 http://archive.debian.org/debian buster/main armhf python3-pip all 18.1-5 [171 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.902+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:47 http://archive.debian.org/debian buster/main armhf python3-setuptools all 40.8.0-1 [306 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.971+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:03 rivoplus volumio[3332]: info: Get:48 http://archive.debian.org/debian buster/main armhf python3-wheel all 0.32.3-2 [19.4 kB] Apr 02 23:43:03 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:03.988+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:04 rivoplus volumio[3332]: info: Get:49 http://archive.debian.org/debian buster/main armhf python3-xdg all 0.25-5 [35.9 kB] Apr 02 23:43:04 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:04.004+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:06 rivoplus volumio[3332]: info: Fetched 75.8 MB in 8s (8954 kB/s) Apr 02 23:43:06 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:06.563+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:06 rivoplus volumio[3332]: info: Selecting previously unselected package bzip2. Apr 02 23:43:06 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:06.669+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:07 rivoplus volumio[3332]: [145B blob data] Apr 02 23:43:07 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:07.472+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:07 rivoplus volumio[3332]: [146B blob data] Apr 02 23:43:07 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:07.678+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:08 rivoplus volumio[3332]: [275B blob data] Apr 02 23:43:08 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:08.501+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:08 rivoplus volumio[3332]: [416B blob data] Apr 02 23:43:08 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:08.553+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:08 rivoplus volumio[3332]: info: Preparing to unpack .../00-bzip2_1.0.6-9.2~deb10u1_armhf.deb ... Apr 02 23:43:08 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:08.568+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:08 rivoplus volumio[3332]: info: Unpacking bzip2 (1.0.6-9.2~deb10u1) ... Apr 02 23:43:08 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:08.586+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:09 rivoplus volumio[3332]: info: Selecting previously unselected package binutils-common:armhf. Apr 02 23:43:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:09.678+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:09 rivoplus volumio[3332]: info: Preparing to unpack .../01-binutils-common_2.31.1-16_armhf.deb ... Apr 02 23:43:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:09.752+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:09 rivoplus volumio[3332]: info: Unpacking binutils-common:armhf (2.31.1-16) ... Apr 02 23:43:09 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:09.766+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Selecting previously unselected package libbinutils:armhf. Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.624+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Preparing to unpack .../02-libbinutils_2.31.1-16_armhf.deb ... Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.645+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Unpacking libbinutils:armhf (2.31.1-16) ... Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.665+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Selecting previously unselected package binutils-arm-linux-gnueabihf. Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.915+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Preparing to unpack .../03-binutils-arm-linux-gnueabihf_2.31.1-16_armhf.deb ... Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.933+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:10 rivoplus volumio[3332]: info: Unpacking binutils-arm-linux-gnueabihf (2.31.1-16) ... Apr 02 23:43:10 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:10.955+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:11 rivoplus volumio[3332]: info: Selecting previously unselected package binutils. Apr 02 23:43:11 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:11.912+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:11 rivoplus volumio[3332]: info: Preparing to unpack .../04-binutils_2.31.1-16_armhf.deb ... Apr 02 23:43:11 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:11.930+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:11 rivoplus volumio[3332]: info: Unpacking binutils (2.31.1-16) ... Apr 02 23:43:11 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:11.948+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Selecting previously unselected package libcc1-0:armhf. Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.109+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Preparing to unpack .../05-libcc1-0_8.3.0-6_armhf.deb ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.126+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Unpacking libcc1-0:armhf (8.3.0-6) ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.142+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Selecting previously unselected package libasan5:armhf. Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.321+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Preparing to unpack .../06-libasan5_8.3.0-6_armhf.deb ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.338+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Unpacking libasan5:armhf (8.3.0-6) ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.353+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Selecting previously unselected package libubsan1:armhf. Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.694+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Preparing to unpack .../07-libubsan1_8.3.0-6_armhf.deb ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.711+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Unpacking libubsan1:armhf (8.3.0-6) ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.724+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Selecting previously unselected package libgcc-8-dev:armhf. Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.871+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Preparing to unpack .../08-libgcc-8-dev_8.3.0-6_armhf.deb ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.889+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:12 rivoplus volumio[3332]: info: Unpacking libgcc-8-dev:armhf (8.3.0-6) ... Apr 02 23:43:12 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:12.903+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:13 rivoplus volumio[3332]: info: Selecting previously unselected package gcc-8. Apr 02 23:43:13 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:13.344+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:13 rivoplus volumio[3332]: info: Preparing to unpack .../09-gcc-8_8.3.0-6_armhf.deb ... Apr 02 23:43:13 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:13.364+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:13 rivoplus volumio[3332]: info: Unpacking gcc-8 (8.3.0-6) ... Apr 02 23:43:13 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:13.377+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Selecting previously unselected package gcc. Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.548+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Preparing to unpack .../10-gcc_4%3a8.3.0-1_armhf.deb ... Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.567+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Unpacking gcc (4:8.3.0-1) ... Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.581+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Selecting previously unselected package libstdc++-8-dev:armhf. Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.735+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Preparing to unpack .../11-libstdc++-8-dev_8.3.0-6_armhf.deb ... Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.751+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:15 rivoplus volumio[3332]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6) ... Apr 02 23:43:15 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:15.775+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:17 rivoplus volumio[3332]: info: Selecting previously unselected package g++-8. Apr 02 23:43:17 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:17.474+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:17 rivoplus volumio[3332]: info: Preparing to unpack .../12-g++-8_8.3.0-6_armhf.deb ... Apr 02 23:43:17 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:17.493+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:17 rivoplus volumio[3332]: info: Unpacking g++-8 (8.3.0-6) ... Apr 02 23:43:17 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:17.508+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Selecting previously unselected package g++. Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.037+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Preparing to unpack .../13-g++_4%3a8.3.0-1_armhf.deb ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.054+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Unpacking g++ (4:8.3.0-1) ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.068+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Selecting previously unselected package make. Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.161+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Preparing to unpack .../14-make_4.2.1-1.2_armhf.deb ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.183+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Unpacking make (4.2.1-1.2) ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.201+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Selecting previously unselected package patch. Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.387+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Preparing to unpack .../15-patch_2.7.6-3+deb10u1_armhf.deb ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.406+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Unpacking patch (2.7.6-3+deb10u1) ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.424+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.541+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.541+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.542+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.543+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.544+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.545+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:20 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:20 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:20 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:20 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:20 rivoplus shairport-sync[3736]: {"time":1775165313985,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Apr 02 23:43:20 rivoplus shairport-sync[3736]: Dload Upload Total Spent Left Speed Apr 02 23:43:20 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:20 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:20 rivoplus volumio[3332]: info: Selecting previously unselected package dpkg-dev. Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.592+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Preparing to unpack .../16-dpkg-dev_1.19.8_all.deb ... Apr 02 23:43:20 rivoplus volumio[3332]: info: Unpacking dpkg-dev (1.19.8) ... Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.605+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.605+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:20 rivoplus volumio[3332]: info: Apr 02 23:43:20 rivoplus volumio[3332]: ---------------------------- Client requests Stop Airplay Playback Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Apr 02 23:43:20 rivoplus volumio[3332]: info: Airplay playback stop Apr 02 23:43:20 rivoplus volumio[3332]: info: Airplay Playback Stopped, clearing state Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::resetVolumioState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::getcurrentVolume Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioRetrievevolume Apr 02 23:43:20 rivoplus shairport-sync[3736]: [158B blob data] Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:20 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:20 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.643+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:20 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:20.643+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:20 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:20 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:21 rivoplus volumio[3332]: info: Selecting previously unselected package build-essential. Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.438+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Preparing to unpack .../17-build-essential_12.6_armhf.deb ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.480+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Unpacking build-essential (12.6) ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.494+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Selecting previously unselected package dh-python. Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.606+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Preparing to unpack .../18-dh-python_3.20190308_all.deb ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.623+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Unpacking dh-python (3.20190308) ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.638+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Selecting previously unselected package libfakeroot:armhf. Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Preparing to unpack .../19-libfakeroot_1.23-1_armhf.deb ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.849+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:21 rivoplus volumio[3332]: info: Unpacking libfakeroot:armhf (1.23-1) ... Apr 02 23:43:21 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:21.863+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Selecting previously unselected package fakeroot. Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.020+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Preparing to unpack .../20-fakeroot_1.23-1_armhf.deb ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.036+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Unpacking fakeroot (1.23-1) ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.052+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Selecting previously unselected package libalgorithm-diff-perl. Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.181+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Preparing to unpack .../21-libalgorithm-diff-perl_1.19.03-2_all.deb ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.201+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.218+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.765+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Preparing to unpack .../22-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.794+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.810+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Selecting previously unselected package libalgorithm-merge-perl. Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.956+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Preparing to unpack .../23-libalgorithm-merge-perl_0.08-3_all.deb ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.972+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:22 rivoplus volumio[3332]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Apr 02 23:43:22 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:22.990+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Selecting previously unselected package libexpat1-dev:armhf. Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.127+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Preparing to unpack .../24-libexpat1-dev_2.2.6-2+deb10u4_armhf.deb ... Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.142+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.157+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Selecting previously unselected package libpython3.7-dev:armhf. Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.360+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Preparing to unpack .../25-libpython3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.374+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:23 rivoplus volumio[3332]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Apr 02 23:43:23 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:23.390+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:24 rivoplus volumio[3332]: info: Airplay started streaming, receiving metadatas Apr 02 23:43:24 rivoplus volumio[3332]: info: Airplay started streaming, receiving metadatas Apr 02 23:43:24 rivoplus shairport-sync[3736]: {"time":1775166200617,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Apr 02 23:43:24 rivoplus shairport-sync[3736]: Dload Upload Total Spent Left Speed Apr 02 23:43:24 rivoplus volumio[3332]: info: Apr 02 23:43:24 rivoplus volumio[3332]: ---------------------------- Client requests Start Airplay PlaybackRoutine Apr 02 23:43:24 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Apr 02 23:43:24 rivoplus volumio[3332]: info: Airplay playback start Apr 02 23:43:24 rivoplus shairport-sync[3736]: [158B blob data] Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.285+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.286+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.301+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.302+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.315+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.316+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:25 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:25 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:25 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:25 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:25 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:25 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:25 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:25 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:25 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:25.355+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:25 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:25 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.111+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.111+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.112+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.115+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.116+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.117+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title= Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:26 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:26 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.151+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.152+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.153+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.154+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:26 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:26.155+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:26 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.47 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetQueue Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::getQueue Apr 02 23:43:32 rivoplus volumio[3332]: info: CorePlayQueue::getQueue Apr 02 23:43:32 rivoplus volumio[3332]: info: Listing playlists Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 02 23:43:32 rivoplus volumio[3332]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 02 23:43:32 rivoplus volumio[3332]: info: Received Get System Info Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 23:43:32 rivoplus volumio[3332]: info: Discovery: Getting this device information Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.110+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.111+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs= volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.112+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.139+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.140+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.140+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.141+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.182+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.183+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.183+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.184+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.185+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.186+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.214+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.214+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.215+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.215+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.215+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.249+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.249+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.250+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.251+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.252+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.253+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.306+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.307+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.308+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.308+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.309+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.356+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.357+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.358+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.359+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.390+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.391+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.392+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.395+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=7000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.396+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.429+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.430+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.430+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.431+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.432+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.433+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.465+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.465+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.466+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.467+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.468+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.740+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.743+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.743+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.744+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.745+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:32 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:32 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.778+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.778+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.780+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.780+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.781+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.782+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:32 rivoplus volumio[3332]: info: Selecting previously unselected package libpython3-dev:armhf. Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.944+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:32 rivoplus volumio[3332]: info: Preparing to unpack .../26-libpython3-dev_3.7.3-1_armhf.deb ... Apr 02 23:43:32 rivoplus volumio[3332]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.955+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:32 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:32.956+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Selecting previously unselected package python-pip-whl. Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.029+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Preparing to unpack .../27-python-pip-whl_18.1-5_all.deb ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.049+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Unpacking python-pip-whl (18.1-5) ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.064+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.232+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.233+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.234+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.234+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.235+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.236+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.265+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.266+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.266+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.265+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.267+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.296+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.296+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.297+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.298+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.299+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.299+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Selecting previously unselected package python3-multidict. Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.473+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Preparing to unpack .../28-python3-multidict_4.5.2-1_armhf.deb ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.489+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Unpacking python3-multidict (4.5.2-1) ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.508+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Selecting previously unselected package python3-yarl. Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.641+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Preparing to unpack .../29-python3-yarl_1.3.0-1_armhf.deb ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.659+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: Unpacking python3-yarl (1.3.0-1) ... Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.675+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.801+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.801+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.801+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.802+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=1000 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.802+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.845+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.846+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.847+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.848+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.849+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:33 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:33 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.883+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.885+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.886+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.887+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:33.886+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:33 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-async-timeout. Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.009+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../30-python3-async-timeout_3.0.1-1_all.deb ... Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-async-timeout (3.0.1-1) ... Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-attr. Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../31-python3-attr_18.2.0-1_all.deb ... Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-attr (18.2.0-1) ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.027+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.029+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.032+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.033+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.043+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-aiohttp. Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.147+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../32-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.162+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.178+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.364+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.365+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.364+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.365+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.367+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.368+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.399+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.400+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.401+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.402+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.404+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::servicePushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:34 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:34 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.434+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.435+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.436+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.437+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Signalling Playback active due to playback status change Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-asn1crypto. Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.523+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../33-python3-asn1crypto_0.24.0-1_all.deb ... Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.536+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.537+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-cffi-backend. Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.633+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../34-python3-cffi-backend_1.12.2-1_armhf.deb ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.660+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.668+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Selecting previously unselected package python3-crypto. Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.874+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Preparing to unpack .../35-python3-crypto_2.6.1-9+b1_armhf.deb ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.900+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:34 rivoplus volumio[3332]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Apr 02 23:43:34 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:34.916+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Selecting previously unselected package python3-cryptography. Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.325+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Preparing to unpack .../36-python3-cryptography_2.6.1-3+deb10u2_armhf.deb ... Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.347+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Unpacking python3-cryptography (2.6.1-3+deb10u2) ... Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.366+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Selecting previously unselected package python3-dbus. Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.670+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Preparing to unpack .../37-python3-dbus_1.2.8-3_armhf.deb ... Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.689+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Unpacking python3-dbus (1.2.8-3) ... Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.705+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Selecting previously unselected package python3.7-dev. Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.853+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio[3332]: info: Preparing to unpack .../38-python3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Apr 02 23:43:35 rivoplus volumio[3332]: info: Unpacking python3.7-dev (3.7.3-2+deb10u3) ... Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.878+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:35 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:35.885+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-dev. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.019+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../39-python3-dev_3.7.3-1_armhf.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.035+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Unpacking python3-dev (3.7.3-1) ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.052+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-entrypoints. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.170+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../40-python3-entrypoints_0.3-1_all.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.187+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Unpacking python3-entrypoints (0.3-1) ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.204+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-gi. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.314+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../41-python3-gi_3.30.4-1_armhf.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.334+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Unpacking python3-gi (3.30.4-1) ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.351+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-secretstorage. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.545+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../42-python3-secretstorage_2.3.1-2_all.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.563+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Unpacking python3-secretstorage (2.3.1-2) ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.583+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-keyring. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.751+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../43-python3-keyring_17.1.1-1_all.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.774+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Unpacking python3-keyring (17.1.1-1) ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.789+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Selecting previously unselected package python3-keyrings.alt. Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:36 rivoplus volumio[3332]: info: Preparing to unpack .../44-python3-keyrings.alt_3.1.1-1_all.deb ... Apr 02 23:43:36 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:36.995+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.012+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Selecting previously unselected package python3-pip. Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.163+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Preparing to unpack .../45-python3-pip_18.1-5_all.deb ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.183+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Unpacking python3-pip (18.1-5) ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.201+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Selecting previously unselected package python3-setuptools. Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.477+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Preparing to unpack .../46-python3-setuptools_40.8.0-1_all.deb ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.497+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Unpacking python3-setuptools (40.8.0-1) ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.511+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Selecting previously unselected package python3-wheel. Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.789+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Preparing to unpack .../47-python3-wheel_0.32.3-2_all.deb ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.812+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Unpacking python3-wheel (0.32.3-2) ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.827+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Selecting previously unselected package python3-xdg. Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:37 rivoplus volumio[3332]: info: Preparing to unpack .../48-python3-xdg_0.25-5_all.deb ... Apr 02 23:43:37 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:37.995+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:38 rivoplus volumio[3332]: info: Unpacking python3-xdg (0.25-5) ... Apr 02 23:43:38 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:38.009+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:38 rivoplus volumio[3332]: info: Setting up python3-entrypoints (0.3-1) ... Apr 02 23:43:38 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:38.267+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:39 rivoplus volumio[3332]: info: Setting up dh-python (3.20190308) ... Apr 02 23:43:39 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:39.136+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:40 rivoplus volumio[3332]: info: Setting up python3-attr (18.2.0-1) ... Apr 02 23:43:40 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:40.017+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:40 rivoplus volumio[3332]: info: Setting up python3-dbus (1.2.8-3) ... Apr 02 23:43:40 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:40.886+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:41 rivoplus volumio[3332]: info: Setting up python3-setuptools (40.8.0-1) ... Apr 02 23:43:41 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:41.744+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:43 rivoplus volumio[3332]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Apr 02 23:43:43 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:43.222+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:43 rivoplus volumio[3332]: info: Setting up binutils-common:armhf (2.31.1-16) ... Apr 02 23:43:43 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:43.257+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:43 rivoplus volumio[3332]: info: Setting up python3-xdg (0.25-5) ... Apr 02 23:43:43 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:43.276+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:44 rivoplus volumio[3332]: info: Setting up bzip2 (1.0.6-9.2~deb10u1) ... Apr 02 23:43:44 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:44.173+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:44 rivoplus volumio[3332]: info: Setting up python3-wheel (0.32.3-2) ... Apr 02 23:43:44 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:44.195+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:44 rivoplus volumio[3332]: info: Setting up python3-multidict (4.5.2-1) ... Apr 02 23:43:45 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:45.004+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:45 rivoplus volumio[3332]: info: Setting up libfakeroot:armhf (1.23-1) ... Apr 02 23:43:45 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:45.775+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:45 rivoplus volumio[3332]: info: Setting up python3-async-timeout (3.0.1-1) ... Apr 02 23:43:45 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:45.810+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: Setting up fakeroot (1.23-1) ... Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.557+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.630+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.649+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio[3332]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.671+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.675+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.678+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.681+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.682+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: Setting up libasan5:armhf (8.3.0-6) ... Apr 02 23:43:46 rivoplus volumio[3332]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.741+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: Setting up make (4.2.1-1.2) ... Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.749+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.751+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:46 rivoplus volumio[3332]: info: Setting up python3-gi (3.30.4-1) ... Apr 02 23:43:46 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:46.785+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:47 rivoplus volumio[3332]: info: Setting up patch (2.7.6-3+deb10u1) ... Apr 02 23:43:47 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:47.784+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:47 rivoplus volumio[3332]: info: Setting up libubsan1:armhf (8.3.0-6) ... Apr 02 23:43:47 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:47.815+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:47 rivoplus volumio[3332]: info: Setting up python3-crypto (2.6.1-9+b1) ... Apr 02 23:43:47 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:47.839+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:49 rivoplus volumio[3332]: info: Setting up python-pip-whl (18.1-5) ... Apr 02 23:43:49 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:49.260+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:49 rivoplus volumio[3332]: info: Setting up libbinutils:armhf (2.31.1-16) ... Apr 02 23:43:49 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:49.284+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:49 rivoplus volumio[3332]: info: Setting up binutils-arm-linux-gnueabihf (2.31.1-16) ... Apr 02 23:43:49 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:49.315+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:49 rivoplus volumio[3332]: info: Setting up python3-yarl (1.3.0-1) ... Apr 02 23:43:49 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:49.334+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:50 rivoplus volumio[3332]: info: Enabling plugin fusiondsp Apr 02 23:43:50 rivoplus volumio[3332]: info: Loading plugin "fusiondsp"... Apr 02 23:43:50 rivoplus volumio[3332]: info: Preparing to generate the ALSA configuration file Apr 02 23:43:50 rivoplus volumio[3332]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 02 23:43:50 rivoplus volumio[3332]: info: Reading ALSA contributions from plugins. Apr 02 23:43:50 rivoplus volumio[3332]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Apr 02 23:43:50 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:50.691+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:50 rivoplus volumio[3332]: info: Setting up python3-asn1crypto (0.24.0-1) ... Apr 02 23:43:50 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:50.700+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:50 rivoplus volumio[3332]: info: Asound.conf file unchanged, so no further update is needed Apr 02 23:43:50 rivoplus volumio[3332]: info: Output device has changed, restarting MPD Apr 02 23:43:50 rivoplus sudo[7454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:43:50 rivoplus sudo[7454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:50 rivoplus volumio[3332]: info: Output device has changed, restarting Shairport Sync Apr 02 23:43:50 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:50 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:43:50 rivoplus sudo[7454]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:50 rivoplus sudo[7457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:43:50 rivoplus sudo[7457]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:50 rivoplus systemd[1]: Stopping Music Player Daemon... Apr 02 23:43:50 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:50 rivoplus volumio[3332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:43:50 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:50 rivoplus volumio[3332]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 02 23:43:50 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:50 rivoplus volumio[3332]: info: QobuzConnect: setDeactiveState invoked Apr 02 23:43:50 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:50 rivoplus volumio[3332]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 02 23:43:50 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:50 rivoplus vtcs[3788]: [2026-04-02 23:43:50.939] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 02 23:43:50 rivoplus vtcs[3788]: [2026-04-02 23:43:50.944] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Apr 02 23:43:50 rivoplus vtcs[3788]: [2026-04-02 23:43:50.944] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 02 23:43:50 rivoplus volumio[3332]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 02 23:43:50 rivoplus volumio[3332]: info: PLUGIN START: fusiondsp Apr 02 23:43:50 rivoplus volumio[3332]: info: Loading i18n strings for locale en Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: FusionDsp - mixtype--------------------- None Apr 02 23:43:51 rivoplus volumio[3332]: info: Preparing to generate the ALSA configuration file Apr 02 23:43:51 rivoplus sudo[7468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 23:43:51 rivoplus sudo[7468]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:51 rivoplus volumio[3332]: info: Done. Apr 02 23:43:51 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Apr 02 23:43:51 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 02 23:43:51 rivoplus systemd[1]: mpd.service: Succeeded. Apr 02 23:43:51 rivoplus systemd[1]: Stopped Music Player Daemon. Apr 02 23:43:51 rivoplus systemd[1]: vtcs.service: Succeeded. Apr 02 23:43:51 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Apr 02 23:43:51 rivoplus sudo[7468]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:51 rivoplus sudo[7471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 23:43:51 rivoplus sudo[7471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:51 rivoplus systemd[1]: Starting Music Player Daemon... Apr 02 23:43:51 rivoplus sudo[7471]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:51 rivoplus volumio[3332]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 02 23:43:51 rivoplus volumio[3332]: info: Reading ALSA contributions from plugins. Apr 02 23:43:51 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 23:43:51 rivoplus sudo[7488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 02 23:43:51 rivoplus sudo[7488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:51 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 23:43:51 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 02 23:43:51 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 23:43:51 rivoplus sudo[7488]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 23:43:51 rivoplus volumio[3332]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 02 23:43:51 rivoplus volumio[3332]: info: MPD Permissions set Apr 02 23:43:51 rivoplus sudo[7490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus sudo[7490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus sudo[7477]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 02 23:43:51 rivoplus sudo[7477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:51 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 02 23:43:51 rivoplus qobuz-connect[3621]: 20260402 23:43:51.327 [3621.3621] INFO SampleApp: Stopping Local configuration server Apr 02 23:43:51 rivoplus qobuz-connect[3621]: 20260402 23:43:51.338 [3621.3621] INFO SampleApp: shat down connection on UNIX socket Apr 02 23:43:51 rivoplus sudo[7477]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:51 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Apr 02 23:43:51 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 02 23:43:51 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Apr 02 23:43:51 rivoplus sudo[7490]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:43:51 rivoplus volumio[3332]: info: Not Reporting Auto name since its the default one Apr 02 23:43:51 rivoplus volumio[3332]: info: FusionDsp - Apr 02 23:43:51 rivoplus volumio[3332]: info: FusionDsp - Apr 02 23:43:52 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 23:43:52 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 02 23:43:52 rivoplus volumio[3332]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 02 23:43:52 rivoplus volumio[3332]: info: QobuzConnect: setDeactiveState invoked Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: Not Reporting Auto name since its the default one Apr 02 23:43:52 rivoplus volumio[3332]: info: FusionDsp - undefined Apr 02 23:43:52 rivoplus volumio[3332]: info: FusionDsp - undefined Apr 02 23:43:52 rivoplus volumio[3332]: info: Executing endpoint qc_getconfig Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.409 [7497.7497] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 02 23:43:52 rivoplus volumio[3332]: info: Starting Shairport Sync Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.421 [7497.7497] INFO VolumeManager: [0xab9de4b0]: Setting new playback volume: 75 Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.422 [7497.7497] INFO VolumeManager: [0xab9de4b0]: Setting new mute state: 0 Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.422 [7497.7497] INFO AudioStreamManager: [0xab9de208]: Setting new audio download buffer size: 1048576 Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.423 [7497.7497] INFO QobuzConnect: [0xab9ded78]: Client initialized! Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.424 [7497.7497] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.443 [7497.7497] INFO LocalConfigManager: [0xab9ddf30]: Starting Local Configuration server Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.444 [7497.7497] INFO SampleApp: Starting Local configuration server Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.445 [7497.7497] INFO SampleApp: Connected to UNIX socket client 0xab9d3ed8 Apr 02 23:43:52 rivoplus volumio[3332]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 02 23:43:52 rivoplus volumio[3332]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 02 23:43:52 rivoplus sudo[7527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 23:43:52 rivoplus sudo[7527]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:52 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 02 23:43:52 rivoplus shairport-sync[3736]: {"time":1775166204336,"response":"startAirplayPlayback Success"} Apr 02 23:43:52 rivoplus systemd[1]: shairport-sync.service: Succeeded. Apr 02 23:43:52 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 02 23:43:52 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:52 rivoplus qobuz-connect[7497]: 20260402 23:43:52.559 [7497.7497] INFO SampleApp: Playback volume changed: 75 Apr 02 23:43:52 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 02 23:43:52 rivoplus sudo[7527]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:52 rivoplus volumio[3332]: info: Setting up libcc1-0:armhf (8.3.0-6) ... Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.645+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:52 rivoplus volumio[3332]: info: Setting up python3-cffi-backend (1.12.2-1) ... Apr 02 23:43:52 rivoplus volumio[3332]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Apr 02 23:43:52 rivoplus volumio[3332]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Apr 02 23:43:52 rivoplus volumio[3332]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.672+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.674+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.675+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.677+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 23:43:52 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:52.686+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.47:51304 @ 0x3400990" latency=14.834391ms timeout=3s volume=86 Apr 02 23:43:52 rivoplus sudo[7536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 02 23:43:52 rivoplus sudo[7536]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:52 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 23:43:52 rivoplus volumio[3332]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 02 23:43:52 rivoplus volumio[3332]: info: Asound.conf file unchanged, so no further update is needed Apr 02 23:43:52 rivoplus volumio[3332]: info: Output device has changed, restarting MPD Apr 02 23:43:52 rivoplus sudo[7536]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:52 rivoplus sudo[7540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 02 23:43:52 rivoplus sudo[7540]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:52 rivoplus systemd[1]: Stopping RAAT DAEMON... Apr 02 23:43:52 rivoplus volumio[3332]: info: Output device has changed, restarting Shairport Sync Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:43:52 rivoplus sudo[7541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:43:52 rivoplus sudo[7541]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:52 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 02 23:43:52 rivoplus systemd[1]: raat-daemon.service: Succeeded. Apr 02 23:43:52 rivoplus sudo[7541]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:52 rivoplus systemd[1]: Stopped RAAT DAEMON. Apr 02 23:43:52 rivoplus systemd[1]: Started RAAT DAEMON. Apr 02 23:43:52 rivoplus sudo[7540]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:52 rivoplus sudo[7544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:43:52 rivoplus sudo[7544]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:52 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:52 rivoplus volumio[3332]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:43:52 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:52 rivoplus volumio[3332]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus volumio[3332]: info: QobuzConnect: setDeactiveState invoked Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:52 rivoplus volumio[3332]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 02 23:43:52 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:52 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 02 23:43:52 rivoplus systemd[1]: mpd.service: Succeeded. Apr 02 23:43:52 rivoplus systemd[1]: Stopped Music Player Daemon. Apr 02 23:43:52 rivoplus systemd[1]: Starting Music Player Daemon... Apr 02 23:43:52 rivoplus volumio[3332]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 02 23:43:52 rivoplus volumio[3332]: info: Preparing to generate the ALSA configuration file Apr 02 23:43:53 rivoplus sudo[7565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 23:43:53 rivoplus sudo[7565]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus sudo[7568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 23:43:53 rivoplus sudo[7565]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus sudo[7568]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus sudo[7561]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 02 23:43:53 rivoplus sudo[7561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus sudo[7568]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus sudo[7561]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 02 23:43:53 rivoplus sudo[7577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 02 23:43:53 rivoplus sudo[7577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus sudo[7577]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus sudo[7582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 02 23:43:53 rivoplus volumio[3332]: xcb_connection_has_error() returned true Apr 02 23:43:53 rivoplus sudo[7582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 02 23:43:53 rivoplus qobuz-connect[7497]: 20260402 23:43:53.304 [7497.7497] INFO SampleApp: Stopping Local configuration server Apr 02 23:43:53 rivoplus qobuz-connect[7497]: 20260402 23:43:53.314 [7497.7497] INFO SampleApp: shat down connection on UNIX socket Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:53 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Apr 02 23:43:53 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 02 23:43:53 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Apr 02 23:43:53 rivoplus sudo[7587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 02 23:43:53 rivoplus sudo[7587]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus sudo[7582]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus volumio[3332]: info: VolumeController::SetAlsaVolume86 Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreStateMachine::pushState Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::volumioPushState Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::volumioGetState Apr 02 23:43:53 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output update for this device Apr 02 23:43:53 rivoplus volumio[3332]: info: MRS: Pushing multiroomSync output Apr 02 23:43:53 rivoplus sudo[7587]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:53.388+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" state=STATUS_PLAYING positionMs=18000 volume=100 Apr 02 23:43:53 rivoplus volumio5-onboarding[3576]: time=2026-04-02T23:43:53.388+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:51304 @ 0x3400990" id= title="Let Me Be" Apr 02 23:43:53 rivoplus volumio[3332]: info: Shairport-Sync Started Apr 02 23:43:53 rivoplus sudo[7590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 02 23:43:53 rivoplus sudo[7590]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus systemd[1]: Stopping RAAT DAEMON... Apr 02 23:43:53 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 02 23:43:53 rivoplus systemd[1]: raat-daemon.service: Succeeded. Apr 02 23:43:53 rivoplus systemd[1]: Stopped RAAT DAEMON. Apr 02 23:43:53 rivoplus systemd[1]: Started RAAT DAEMON. Apr 02 23:43:53 rivoplus sudo[7590]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus volumio[3332]: info: camilladsp service started and running in background, instance 1 Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:53 rivoplus volumio[3332]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 02 23:43:53 rivoplus volumio[3332]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 02 23:43:53 rivoplus volumio[3332]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 02 23:43:53 rivoplus volumio[3332]: info: FusionDsp loaded Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 23:43:53 rivoplus volumio[3332]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 02 23:43:53 rivoplus volumio[3332]: info: Adding Signal Path Element [object Object] Apr 02 23:43:53 rivoplus volumio[3332]: info: Adding fusiondspeq DSP Signal Path Element Apr 02 23:43:53 rivoplus volumio[3332]: info: Additional DSP elements updated Apr 02 23:43:53 rivoplus sudo[7618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 02 23:43:53 rivoplus sudo[7618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:53 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:53 rivoplus volumio[3332]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 02 23:43:53 rivoplus volumio[3332]: info: Additional DSP elements updated Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:53 rivoplus volumio[3332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:43:53 rivoplus volumio[3332]: info: Updating RAAT Signal Path Apr 02 23:43:53 rivoplus volumio[3332]: info: FusionDsp - ---- installed callbackRead Apr 02 23:43:53 rivoplus volumio[3332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 23:43:53 rivoplus sudo[7618]: pam_unix(sudo:session): session closed for user root Apr 02 23:43:53 rivoplus volumio[3332]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 02 23:43:53 rivoplus volumio[3332]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 02 23:43:53 rivoplus volumio[3332]: at onErrorNT (internal/child_process.js:472:16) Apr 02 23:43:53 rivoplus volumio[3332]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 02 23:43:53 rivoplus volumio[3332]: at runNextTicks (internal/process/task_queues.js:64:3) Apr 02 23:43:53 rivoplus volumio[3332]: at listOnTimeout (internal/timers.js:526:9) Apr 02 23:43:53 rivoplus volumio[3332]: at processTimers (internal/timers.js:500:7) { Apr 02 23:43:53 rivoplus volumio[3332]: errno: -2, Apr 02 23:43:53 rivoplus volumio[3332]: code: 'ENOENT', Apr 02 23:43:53 rivoplus volumio[3332]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 02 23:43:53 rivoplus volumio[3332]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 02 23:43:53 rivoplus volumio[3332]: spawnargs: [ Apr 02 23:43:53 rivoplus volumio[3332]: '-p', Apr 02 23:43:53 rivoplus volumio[3332]: 9876, Apr 02 23:43:53 rivoplus volumio[3332]: '-o', Apr 02 23:43:53 rivoplus volumio[3332]: '/tmp/camilladsp.log', Apr 02 23:43:53 rivoplus volumio[3332]: '-l', Apr 02 23:43:53 rivoplus volumio[3332]: 'warn', Apr 02 23:43:53 rivoplus volumio[3332]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 02 23:43:53 rivoplus volumio[3332]: ] Apr 02 23:43:53 rivoplus volumio[3332]: } Apr 02 23:43:53 rivoplus volumio[3332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 23:43:54 rivoplus sudo[7638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 23:42 Apr 02 23:43:54 rivoplus sudo[7638]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"