Apr 11 20:23:03 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:03.247+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.202:56324 error="websocket: close 1006 (abnormal closure): unexpected EOF" Apr 11 20:23:03 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:03.248+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.202:56324 Apr 11 20:23:03 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:03.248+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.202:56324 Apr 11 20:23:03 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:03.882+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:03 volumio-tv volumio[1340]: info: Checking if install.sh is present Apr 11 20:23:03 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:03.893+02:00 level=ERROR msg="failed reading message" error="websocket: close 1000 (normal)" Apr 11 20:23:03 volumio-tv volumio[1340]: info: Executing install.sh Apr 11 20:23:03 volumio-tv sudo[11326]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/rp2/install.sh Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.153 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 11 20:23:03 volumio-tv sudo[11326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 20:23:03 volumio-tv sudo[11328]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Apr 11 20:23:03 volumio-tv sudo[11328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.153 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 11 20:23:03 volumio-tv volumio[1340]: info: Installing mpv media player... Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 11 20:23:03 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.153 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Apr 11 20:23:04 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Apr 11 20:23:04 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:04 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetQueue Apr 11 20:23:04 volumio-tv volumio[1340]: info: CoreStateMachine::getQueue Apr 11 20:23:04 volumio-tv volumio[1340]: info: CorePlayQueue::getQueue Apr 11 20:23:04 volumio-tv volumio[1340]: info: Listing playlists Apr 11 20:23:04 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Apr 11 20:23:05 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:05 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:05 volumio-tv volumio[1340]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Apr 11 20:23:05 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:05 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:05 volumio-tv volumio[1340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Apr 11 20:23:05 volumio-tv volumio[1340]: info: Get:1 http://archive.raspberrypi.com/debian bookworm InRelease [55.0 kB] Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.437+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:05 volumio-tv volumio[1340]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease [15.0 kB] Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.462+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.718+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.202:52118 Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.762+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.202:52118 @ 0x2c01b00" latency=553.49598ms platform=PLATFORM_ANDROID version=5.260331.0 Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.764+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.202:52118 @ 0x2c01b00" latency=555.196963ms timeout=10s Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.764+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" Apr 11 20:23:05 volumio-tv volumio[1340]: info: Received Get System Info Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:23:05 volumio-tv volumio[1340]: info: Discovery: Getting this device information Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:05 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.766+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" name=volumio-tv Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.777+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" language=pl Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.779+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" timezone=Europe/Warsaw Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.780+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" available=true connected=true macAddress=d8:3a:dd:dd:07:ac ip4Address=192.168.0.103/24 ip6Address= Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.781+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.782+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" setupComplete=true Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 0 info | grep "bcm2835 ALSA" Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 1 info | grep "bcm2835 Headphones" Apr 11 20:23:05 volumio-tv volumio[1340]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 2 info | grep "vc4-hdmi-0" Apr 11 20:23:05 volumio-tv volumio[1340]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 3 info | grep "vc4-hdmi-1" Apr 11 20:23:05 volumio-tv volumio[1340]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Apr 11 20:23:05 volumio-tv volumio[1340]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Apr 11 20:23:05 volumio-tv volumio[1340]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 20:23:05 volumio-tv volumio[1340]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 5 info | grep "USB HIFI AUDIO" Apr 11 20:23:05 volumio-tv volumio[1340]: Card sysdefault:5 'AUDIO'/'C-Media Electronics Inc. USB HIFI AUDIO at usb-0000:01:00.0-1.2, full speed' Apr 11 20:23:05 volumio-tv volumio[1340]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Apr 11 20:23:05 volumio-tv volumio[1340]: /bin/sh: 1: /usr/local/bin/alsacap: not found Apr 11 20:23:05 volumio-tv volumio[1340]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Apr 11 20:23:05 volumio-tv volumio[1340]: amixer -c 5 info | grep "USB HIFI AUDIO" Apr 11 20:23:05 volumio-tv volumio[1340]: Card sysdefault:5 'AUDIO'/'C-Media Electronics Inc. USB HIFI AUDIO at usb-0000:01:00.0-1.2, full speed' Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.994+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" selectedOutputId=5 Apr 11 20:23:05 volumio-tv volumio[1340]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf Packages [14.5 MB] Apr 11 20:23:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:05.996+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:23:05 volumio-tv volumio[1340]: info: Discovery: Getting this device information Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:05 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:05 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:23:06 volumio-tv volumio[1340]: info: Received Get System Info Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:23:06 volumio-tv volumio[1340]: info: Discovery: Getting this device information Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:06 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.008+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" currentVersion=4.119 latestVersion=4.119 Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.008+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" status=UPDATE_STATUS_NONE progress=0 Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.008+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" userId= Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.008+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" providers=3 Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.008+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" plugins=63 Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.011+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" state=STATUS_PAUSED positionMs=46622 volume=32 Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:06 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" id=http://stream-uk1.radioparadise.com/aac-320 title="Radio Paradise Main Mix (EU) 320k AAC" Apr 11 20:23:06 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103:3000 from 192.168.0.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 20:23:06 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 20:23:06 volumio-tv volumio[1340]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf Packages [566 kB] Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.105+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:06 volumio-tv volumio[1340]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/untested armhf Packages [1114 B] Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.609+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:06 volumio-tv volumio[1340]: info: Get:6 http://raspbian.raspberrypi.com/raspbian bookworm/contrib armhf Packages [62.3 kB] Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.651+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:06 volumio-tv volumio[1340]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/non-free armhf Packages [118 kB] Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.787+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:06 volumio-tv volumio[1340]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/rpi armhf Packages [1360 B] Apr 11 20:23:06 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:06.960+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:07 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:07.281+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.202:52118 @ 0x2c01b00" latency=558.547829ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 11 20:23:07 volumio-tv sudo[11518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 20:23:07 volumio-tv sudo[11518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 20:23:07 volumio-tv sudo[11518]: pam_unix(sudo:session): session closed for user root Apr 11 20:23:07 volumio-tv sudo[11520]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 20:23:07 volumio-tv sudo[11520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 20:23:07 volumio-tv sudo[11520]: pam_unix(sudo:session): session closed for user root Apr 11 20:23:07 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:08 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 20:23:08 volumio-tv sudo[11524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 20:23:08 volumio-tv sudo[11524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 20:23:08 volumio-tv sudo[11524]: pam_unix(sudo:session): session closed for user root Apr 11 20:23:08 volumio-tv sudo[11526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 20:23:08 volumio-tv sudo[11526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 20:23:08 volumio-tv sudo[11526]: pam_unix(sudo:session): session closed for user root Apr 11 20:23:08 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.202 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:08 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:23:08 volumio-tv volumio[1340]: info: Listing playlists Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 20:23:08 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 20:23:09 volumio-tv volumio-remote-updater[765]: [2026-04-11 20:23:09] [connect] Successful connection Apr 11 20:23:09 volumio-tv volumio-remote-updater[765]: [2026-04-11 20:23:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775931789 101 Apr 11 20:23:09 volumio-tv volumio[1340]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 15 Apr 11 20:23:12 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:23:12 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:23:12 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetQueue Apr 11 20:23:12 volumio-tv volumio[1340]: info: CoreStateMachine::getQueue Apr 11 20:23:12 volumio-tv volumio[1340]: info: CorePlayQueue::getQueue Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 20:23:17 volumio-tv volumio[1340]: info: Received Get System Info Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:23:17 volumio-tv volumio[1340]: info: Discovery: Getting this device information Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:17 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:17 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:23:17 volumio-tv go-librespot[9269]: time="2026-04-11T20:23:17+02:00" level=trace msg="sent dealer ping" Apr 11 20:23:17 volumio-tv go-librespot[9269]: time="2026-04-11T20:23:17+02:00" level=trace msg="received dealer pong" Apr 11 20:23:20 volumio-tv volumio[1340]: info: Fetched 15.3 MB in 15s (1033 kB/s) Apr 11 20:23:20 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:20.101+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:24 volumio-tv volumio[1340]: info: Enabling plugin rp2 Apr 11 20:23:24 volumio-tv volumio[1340]: info: Loading plugin "rp2"... Apr 11 20:23:39 volumio-tv sudo[11328]: pam_unix(sudo:session): session closed for user root Apr 11 20:23:41 volumio-tv sudo[11563]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y mpv Apr 11 20:23:41 volumio-tv sudo[11563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 20:23:41 volumio-tv volumio[1340]: info: PLUGIN START: rp2 Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 20:23:41 volumio-tv volumio[1340]: info: [1775931821824] CoreMusicLibrary::Adding element Radio Paradise (RP2) Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:23:41 volumio-tv volumio[1340]: Cannot find translation for source Spotify Apr 11 20:23:41 volumio-tv volumio[1340]: Cannot find translation for source Radio Paradise (RP2) Apr 11 20:23:41 volumio-tv volumio[1340]: info: Done. Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 20:23:41 volumio-tv volumio[1340]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 11 20:23:41 volumio-tv volumio[1340]: info: Received Get System Version Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 20:23:41 volumio-tv volumio[1340]: info: Received Get System Info Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:23:41 volumio-tv volumio[1340]: info: Discovery: Getting this device information Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioGetState Apr 11 20:23:41 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:23:41 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:23:41 volumio-tv volumio[1340]: info: Reading package lists... Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.855+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio[1340]: info: Building dependency tree... Apr 11 20:23:41 volumio-tv volumio[1340]: info: Reading state information... Apr 11 20:23:41 volumio-tv volumio[1340]: info: 9 packages can be upgraded. Run 'apt list --upgradable' to see them. Apr 11 20:23:41 volumio-tv volumio[1340]: info: Reading package lists... Apr 11 20:23:41 volumio-tv volumio[1340]: info: Building dependency tree... Apr 11 20:23:41 volumio-tv volumio[1340]: info: Reading state information... Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.861+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.862+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.862+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.862+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.863+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:41 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:41.863+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: The following additional packages will be installed: Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.681+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: cpp cpp-12 libdvdnav4 libdvdread8 libegl-mesa0 libegl1 libfile-basedir-perl Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.689+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libfontenc1 libgles2 libice6 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libio-stringy-perl libipc-system-simple-perl libisl23 liblua5.2-0 libmpc3 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libmpfr6 libmujs2 libnet-dbus-perl libsixel1 libsm6 libtie-ixhash-perl Apr 11 20:23:42 volumio-tv volumio[1340]: info: libuchardet0 libva-wayland2 libx11-protocol-perl libxaw7 libxcomposite1 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxft2 libxinerama1 libxkbfile1 libxml-parser-perl libxml-twig-perl Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxml-xpathengine-perl libxmu6 libxmuu1 libxpm4 libxpresent1 libxt6 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxtst6 libxxf86dga1 python3-brotli python3-mutagen python3-pyxattr Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.696+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.696+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.699+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: python3-websockets rtmpdump x11-utils x11-xserver-utils xdg-utils yt-dlp Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.714+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: zutty Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.716+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: Suggested packages: Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.721+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: cpp-doc gcc-12-locales cpp-12-doc libdvdcss2 libunicode-map8-perl Apr 11 20:23:42 volumio-tv volumio[1340]: info: libunicode-string-perl xml-twig-tools python-mutagen-doc python-pyxattr-doc Apr 11 20:23:42 volumio-tv volumio[1340]: info: mesa-utils nickle cairo-5c xorg-docs-core libfribidi-bin | bidiv phantomjs Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.725+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.726+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.726+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: The following NEW packages will be installed: Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.794+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: cpp cpp-12 libdvdnav4 libdvdread8 libegl-mesa0 libegl1 libfile-basedir-perl Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.805+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libfontenc1 libgles2 libice6 Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.815+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: libio-stringy-perl libipc-system-simple-perl libisl23 liblua5.2-0 libmpc3 Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.824+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: libmpfr6 libmujs2 libnet-dbus-perl libsixel1 libsm6 libtie-ixhash-perl Apr 11 20:23:42 volumio-tv volumio[1340]: info: libuchardet0 libva-wayland2 libx11-protocol-perl libxaw7 libxcomposite1 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxft2 libxinerama1 libxkbfile1 libxml-parser-perl libxml-twig-perl Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxml-xpathengine-perl libxmu6 libxmuu1 libxpm4 libxpresent1 libxt6 Apr 11 20:23:42 volumio-tv volumio[1340]: info: libxtst6 libxxf86dga1 mpv python3-brotli python3-mutagen python3-pyxattr Apr 11 20:23:42 volumio-tv volumio[1340]: info: python3-websockets rtmpdump x11-utils x11-xserver-utils xdg-utils yt-dlp Apr 11 20:23:42 volumio-tv volumio[1340]: info: zutty Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.833+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.834+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.834+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.834+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.835+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: 0 upgraded, 52 newly installed, 0 to remove and 9 not upgraded. Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.971+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio[1340]: info: Need to get 14.5 MB of archives. Apr 11 20:23:42 volumio-tv volumio[1340]: info: After this operation, 50.9 MB of additional disk space will be used. Apr 11 20:23:42 volumio-tv volumio[1340]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf libegl-mesa0 armhf 24.2.8-1~bpo12+rpt4 [141 kB] Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.974+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:42 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:42.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:43 volumio-tv volumio[1340]: info: Get:2 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libisl23 armhf 0.25-1.1 [517 kB] Apr 11 20:23:43 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:43.167+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:43 volumio-tv volumio[1340]: info: Get:3 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libmpfr6 armhf 4.2.0-1 [574 kB] Apr 11 20:23:43 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:43.363+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:43 volumio-tv volumio[1340]: info: Get:4 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libmpc3 armhf 1.3.1-1 [41.0 kB] Apr 11 20:23:43 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:43.473+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:43 volumio-tv volumio[1340]: info: Get:6 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf cpp armhf 4:12.2.0-3+rpi1 [7048 B] Apr 11 20:23:43 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:43.841+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:43 volumio-tv volumio[1340]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf cpp-12 armhf 12.2.0-14+rpi1+deb12u1 [7159 kB] Apr 11 20:23:43 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:43.906+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:44 volumio-tv volumio[1340]: info: Get:7 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libdvdread8 armhf 6.1.3-1 [87.4 kB] Apr 11 20:23:44 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:44.155+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:44 volumio-tv volumio[1340]: info: Get:8 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libdvdnav4 armhf 6.1.1-1 [43.3 kB] Apr 11 20:23:44 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:44.460+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:44 volumio-tv volumio[1340]: info: Get:9 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libegl1 armhf 1.6.0-1 [28.3 kB] Apr 11 20:23:44 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:44.754+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:44 volumio-tv volumio[1340]: info: Get:10 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libipc-system-simple-perl all 1.30-2 [26.8 kB] Apr 11 20:23:44 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:44.794+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:11 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-basedir-perl all 0.09-2 [15.1 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.013+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:12 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-desktopentry-perl all 0.22-3 [17.6 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.146+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:13 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-mimeinfo-perl all 0.33-1 [48.3 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.331+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:14 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfontenc1 armhf 1:1.1.4-1 [22.3 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.571+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:15 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libgles2 armhf 1.6.0-1 [17.2 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.657+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:45 volumio-tv volumio[1340]: info: Get:16 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libice6 armhf 2:1.0.10-1 [51.7 kB] Apr 11 20:23:45 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:45.865+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:17 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libio-stringy-perl all 2.111-3 [56.5 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.082+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:18 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf liblua5.2-0 armhf 5.2.4-3 [85.7 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.353+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:20 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxml-parser-perl armhf 2.46-4+deb12u1 [197 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.519+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:19 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libmujs2 armhf 1.3.2-1 [90.7 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.650+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:21 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxml-twig-perl all 1:3.52-2 [181 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.770+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:46 volumio-tv volumio[1340]: info: Get:22 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libnet-dbus-perl armhf 1.2.0-2 [174 kB] Apr 11 20:23:46 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:46.846+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:23 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libsixel1 armhf 1.10.3-3 [81.1 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.137+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:24 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libsm6 armhf 2:1.2.3-1 [32.9 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.219+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:25 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libtie-ixhash-perl all 1.23-4 [11.7 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.470+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:26 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libuchardet0 armhf 0.0.7-1 [65.0 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.563+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv go-librespot[9269]: time="2026-04-11T20:23:47+02:00" level=trace msg="sent dealer ping" Apr 11 20:23:47 volumio-tv go-librespot[9269]: time="2026-04-11T20:23:47+02:00" level=trace msg="received dealer pong" Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:27 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libva-wayland2 armhf 2.17.0-1 [17.1 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.703+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:47 volumio-tv volumio[1340]: info: Get:28 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libx11-protocol-perl all 0.56-9 [120 kB] Apr 11 20:23:47 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:47.876+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:29 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxt6 armhf 1:1.2.1-1.1 [155 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.061+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:30 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxmu6 armhf 2:1.1.3-3 [52.0 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.229+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:31 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxpm4 armhf 1:3.5.12-1.1+deb12u1 [37.7 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.396+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:32 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxaw7 armhf 2:1.0.14-1 [165 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.579+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:33 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxcomposite1 armhf 1:0.4.5-1 [16.2 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.781+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:48 volumio-tv volumio[1340]: info: Get:34 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxft2 armhf 2.3.6-1 [53.7 kB] Apr 11 20:23:48 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:48.991+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:35 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxinerama1 armhf 2:1.1.4-3 [17.4 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.486+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:36 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxkbfile1 armhf 1:1.1.0-1 [66.4 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.503+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:37 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxml-xpathengine-perl all 0.14-2 [33.5 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.533+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:38 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxmuu1 armhf 2:1.1.3-3 [23.3 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.551+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:39 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxpresent1 armhf 1.0.0-2 [7316 B] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.648+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:40 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxtst6 armhf 2:1.2.3-1.1 [26.3 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.666+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:41 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxxf86dga1 armhf 2:1.1.5-1 [20.9 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.693+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:42 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf mpv armhf 0.35.1-4 [1042 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.785+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:50 volumio-tv volumio[1340]: info: Get:43 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-brotli armhf 1.0.9-2+b3 [278 kB] Apr 11 20:23:50 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:50.999+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:44 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf python3-mutagen all 1.46.0-1 [135 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.172+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:45 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf python3-pyxattr armhf 0.8.1-1 [15.8 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.280+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:46 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf python3-websockets all 10.4-1 [62.8 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.393+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:47 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf rtmpdump armhf 2.4+20151223.gitfa8646d.1-2+b2 [47.7 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.627+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:48 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf x11-utils armhf 7.7+5 [176 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.688+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:49 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf x11-xserver-utils armhf 7.7+9 [151 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.770+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:50 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf xdg-utils all 1.1.3-4.1 [75.5 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.821+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:51 volumio-tv volumio[1340]: info: Get:51 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf yt-dlp all 2023.03.04-1 [1778 kB] Apr 11 20:23:51 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:51.918+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:52 volumio-tv volumio[1340]: info: Get:52 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf zutty armhf 0.14.0.20230218+dfsg1-1 [155 kB] Apr 11 20:23:52 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:52.334+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:53 volumio-tv volumio[1340]: info: Fetched 14.5 MB in 9s (1530 kB/s) Apr 11 20:23:53 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:53.313+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:56 volumio-tv volumio[1340]: info: Selecting previously unselected package libisl23:armhf. Apr 11 20:23:56 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:56.209+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:57 volumio-tv volumio[1340]: [620B blob data] Apr 11 20:23:57 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:57.434+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:57 volumio-tv volumio[1340]: info: Preparing to unpack .../00-libisl23_0.25-1.1_armhf.deb ... Apr 11 20:23:57 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:57.440+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:57 volumio-tv volumio[1340]: info: Unpacking libisl23:armhf (0.25-1.1) ... Apr 11 20:23:57 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:57.492+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: Selecting previously unselected package libmpfr6:armhf. Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.051+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: Preparing to unpack .../01-libmpfr6_4.2.0-1_armhf.deb ... Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.059+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: Unpacking libmpfr6:armhf (4.2.0-1) ... Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.076+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: CALLMETHOD: music_service rp2 configSaveGeneralSettings [object Object] Apr 11 20:23:58 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: rp2 , configSaveGeneralSettings Apr 11 20:23:58 volumio-tv volumio[1340]: info: Selecting previously unselected package libmpc3:armhf. Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.689+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: Preparing to unpack .../02-libmpc3_1.3.1-1_armhf.deb ... Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:23:58 volumio-tv volumio[1340]: info: Unpacking libmpc3:armhf (1.3.1-1) ... Apr 11 20:23:58 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:23:58.710+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:00 volumio-tv volumio[1340]: info: Selecting previously unselected package cpp-12. Apr 11 20:24:00 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:00.365+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:00 volumio-tv volumio[1340]: info: Preparing to unpack .../03-cpp-12_12.2.0-14+rpi1+deb12u1_armhf.deb ... Apr 11 20:24:00 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:00.375+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:00 volumio-tv volumio[1340]: info: Unpacking cpp-12 (12.2.0-14+rpi1+deb12u1) ... Apr 11 20:24:00 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:00.391+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:05 volumio-tv volumio[1340]: info: Selecting previously unselected package cpp. Apr 11 20:24:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:05.899+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:05 volumio-tv volumio[1340]: info: Preparing to unpack .../04-cpp_4%3a12.2.0-3+rpi1_armhf.deb ... Apr 11 20:24:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:05.902+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:05 volumio-tv volumio[1340]: info: Unpacking cpp (4:12.2.0-3+rpi1) ... Apr 11 20:24:05 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:05.912+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:07 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 20:24:07 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 20:24:13 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri Apr 11 20:24:14 volumio-tv volumio[1340]: info: Preload queue cleared Apr 11 20:24:16 volumio-tv volumio[1340]: info: Selecting previously unselected package libdvdread8:armhf. Apr 11 20:24:16 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:16.411+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:16 volumio-tv volumio[1340]: info: Preparing to unpack .../05-libdvdread8_6.1.3-1_armhf.deb ... Apr 11 20:24:16 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:16.451+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:17 volumio-tv go-librespot[9269]: time="2026-04-11T20:24:17+02:00" level=trace msg="sent dealer ping" Apr 11 20:24:17 volumio-tv go-librespot[9269]: time="2026-04-11T20:24:17+02:00" level=trace msg="received dealer pong" Apr 11 20:24:17 volumio-tv volumio[1340]: info: Unpacking libdvdread8:armhf (6.1.3-1) ... Apr 11 20:24:17 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:17.908+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:19 volumio-tv volumio[1340]: info: Selecting previously unselected package libdvdnav4:armhf. Apr 11 20:24:19 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:19.594+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:19 volumio-tv volumio[1340]: info: Preparing to unpack .../06-libdvdnav4_6.1.1-1_armhf.deb ... Apr 11 20:24:19 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:19.605+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:19 volumio-tv volumio[1340]: info: Unpacking libdvdnav4:armhf (6.1.1-1) ... Apr 11 20:24:19 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:19.618+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Selecting previously unselected package libegl-mesa0:armhf. Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.332+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Preparing to unpack .../07-libegl-mesa0_24.2.8-1~bpo12+rpt4_armhf.deb ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.340+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Unpacking libegl-mesa0:armhf (24.2.8-1~bpo12+rpt4) ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.349+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Selecting previously unselected package libegl1:armhf. Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.670+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Preparing to unpack .../08-libegl1_1.6.0-1_armhf.deb ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.682+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Unpacking libegl1:armhf (1.6.0-1) ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.704+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Selecting previously unselected package libipc-system-simple-perl. Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.936+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Preparing to unpack .../09-libipc-system-simple-perl_1.30-2_all.deb ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.944+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:21 volumio-tv volumio[1340]: info: Unpacking libipc-system-simple-perl (1.30-2) ... Apr 11 20:24:21 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:21.954+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:22 volumio-tv volumio[1340]: info: Selecting previously unselected package libfile-basedir-perl. Apr 11 20:24:22 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:22.946+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:22 volumio-tv volumio[1340]: info: Preparing to unpack .../10-libfile-basedir-perl_0.09-2_all.deb ... Apr 11 20:24:22 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:22.963+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:22 volumio-tv volumio[1340]: info: Unpacking libfile-basedir-perl (0.09-2) ... Apr 11 20:24:22 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:22.991+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio[1340]: info: Selecting previously unselected package libfile-desktopentry-perl. Apr 11 20:24:23 volumio-tv volumio[1340]: info: Preparing to unpack .../11-libfile-desktopentry-perl_0.22-3_all.deb ... Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.207+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.211+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio[1340]: info: Unpacking libfile-desktopentry-perl (0.22-3) ... Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.224+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio[1340]: info: Selecting previously unselected package libfile-mimeinfo-perl. Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.404+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio[1340]: info: Preparing to unpack .../12-libfile-mimeinfo-perl_0.33-1_all.deb ... Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.408+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:23 volumio-tv volumio[1340]: info: Unpacking libfile-mimeinfo-perl (0.33-1) ... Apr 11 20:24:23 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:23.417+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:25 volumio-tv volumio[1340]: info: Selecting previously unselected package libfontenc1:armhf. Apr 11 20:24:25 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:25.165+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:25 volumio-tv volumio[1340]: info: Preparing to unpack .../13-libfontenc1_1%3a1.1.4-1_armhf.deb ... Apr 11 20:24:25 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:25.173+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:25 volumio-tv volumio[1340]: info: Unpacking libfontenc1:armhf (1:1.1.4-1) ... Apr 11 20:24:25 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:25.185+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Selecting previously unselected package libgles2:armhf. Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.334+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Preparing to unpack .../14-libgles2_1.6.0-1_armhf.deb ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.337+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Unpacking libgles2:armhf (1.6.0-1) ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.347+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Selecting previously unselected package libice6:armhf. Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.557+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Preparing to unpack .../15-libice6_2%3a1.0.10-1_armhf.deb ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.565+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Unpacking libice6:armhf (2:1.0.10-1) ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.573+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Selecting previously unselected package libio-stringy-perl. Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.766+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Preparing to unpack .../16-libio-stringy-perl_2.111-3_all.deb ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.783+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:26 volumio-tv volumio[1340]: info: Unpacking libio-stringy-perl (2.111-3) ... Apr 11 20:24:26 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:26.789+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Selecting previously unselected package liblua5.2-0:armhf. Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.112+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Preparing to unpack .../17-liblua5.2-0_5.2.4-3_armhf.deb ... Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.118+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Unpacking liblua5.2-0:armhf (5.2.4-3) ... Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.144+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Selecting previously unselected package libmujs2:armhf. Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.447+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Preparing to unpack .../18-libmujs2_1.3.2-1_armhf.deb ... Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.456+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Unpacking libmujs2:armhf (1.3.2-1) ... Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.480+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Selecting previously unselected package libxml-parser-perl. Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.710+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:30 volumio-tv volumio[1340]: info: Preparing to unpack .../19-libxml-parser-perl_2.46-4+deb12u1_armhf.deb ... Apr 11 20:24:30 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:30.720+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:31 volumio-tv volumio[1340]: info: Unpacking libxml-parser-perl (2.46-4+deb12u1) ... Apr 11 20:24:31 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:31.202+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 11 20:24:31 volumio-tv volumio[1340]: info: Preload queue cleared Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::ClearQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::stop Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::updateTrackBlock Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrackBlock Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::stPlaybackTimer Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::pushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioPushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::serviceStop Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::serviceStop Apr 11 20:24:31 volumio-tv volumio[1340]: info: ControllerMpd::stop Apr 11 20:24:31 volumio-tv volumio[1340]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::clearPlayQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::saveQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioPushQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::addQueueItems Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::addQueueItems Apr 11 20:24:31 volumio-tv volumio[1340]: info: Preload queue cleared Apr 11 20:24:31 volumio-tv volumio[1340]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D Apr 11 20:24:31 volumio-tv volumio[1340]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D in service rp2 Apr 11 20:24:31 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:31.921+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" state=STATUS_STOPPED positionMs=0 volume=32 Apr 11 20:24:31 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:31.922+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" id=http://stream-uk1.radioparadise.com/aac-320 title="Radio Paradise Main Mix (EU) 320k AAC" Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioPushQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::saveQueue Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::updateTrackBlock Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrackBlock Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioPlay Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::play index 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::stop Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::play index undefined Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::startPlaybackTimer Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 Apr 11 20:24:31 volumio-tv volumio[1340]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Apr 11 20:24:31 volumio-tv volumio[1340]: info: Apr 11 20:24:31 volumio-tv volumio[1340]: ---------------------------- MPD announces state update: player Apr 11 20:24:31 volumio-tv volumio[1340]: info: sendMpdCommand stop took 30 milliseconds Apr 11 20:24:31 volumio-tv volumio[1340]: info: ControllerMpd::getState Apr 11 20:24:31 volumio-tv volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:24:31 volumio-tv volumio[1340]: info: sendMpdCommand status took 1 milliseconds Apr 11 20:24:31 volumio-tv volumio[1340]: verbose: ControllerMpd::parseState Apr 11 20:24:31 volumio-tv volumio[1340]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 20:24:31 volumio-tv volumio[1340]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 20:24:31 volumio-tv volumio[1340]: verbose: ControllerMpd::parseTrackInfo Apr 11 20:24:31 volumio-tv volumio[1340]: info: ControllerMpd::pushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::servicePushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreStateMachine::pushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 20:24:31 volumio-tv volumio[1340]: info: CoreCommandRouter::volumioPushState Apr 11 20:24:31 volumio-tv volumio[1340]: info: CorePlayQueue::getTrack 0 Apr 11 20:24:31 volumio-tv volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd Apr 11 20:24:31 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:31.955+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" state=STATUS_STOPPED positionMs=0 volume=32 Apr 11 20:24:31 volumio-tv volumio5-onboarding[1497]: time=2026-04-11T20:24:31.955+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.202:52118 @ 0x2c01b00" id="rp2/channel@id=0" title="The Main Mix" Apr 11 20:24:31 volumio-tv volumio[1340]: info: ------------------------------ 9ms Apr 11 20:24:31 volumio-tv volumio[1340]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Apr 11 20:24:32 volumio-tv volumio[1340]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=0&audio_type= Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Obtained block for channel "0" Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] ------------- Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Block summary Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] ------------- Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/0/x/2161/4/b/2161-0.flac Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Tracks: Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 0. I Don't Wanna Hear Another Sound (3:25 | elapsed: 0s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 1. Recuerdo de Ti (2:45 | elapsed: 3m 25s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 2. Turn Blue (3:36 | elapsed: 6m 11s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 3. Audience of One (2:56 | elapsed: 9m 47s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 4. Rule #35 - Microphone (4:00 | elapsed: 12m 44s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 5. Senses Working Overtime (4:26 | elapsed: 16m 45s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 6. Your Heart Is an Empty Room (3:32 | elapsed: 21m 11s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] 7. Summer Sun (3:13 | elapsed: 24m 43s) Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Apr 11 20:24:33 volumio-tv volumio[1340]: verbose: [rp2] Current track scheduled playback vs. current time: 4/11/2026, 8:21:31 PM <-> 4/11/2026, 8:24:33 PM Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Going to start playback of current track at 3:01 Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] Starting mpv Apr 11 20:24:33 volumio-tv volumio[1340]: /bin/sh: 1: mpv: not found Apr 11 20:24:33 volumio-tv volumio[1340]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version Apr 11 20:24:33 volumio-tv volumio[1340]: /bin/sh: 1: mpv: not found Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] [mpv] mpv process spawned Apr 11 20:24:33 volumio-tv volumio[1340]: verbose: [rp2] Waiting for player event "playing"... Apr 11 20:24:33 volumio-tv volumio[1340]: info: [rp2] [mpv] (PID: 11873) /bin/sh: 1: mpv: not found Apr 11 20:24:33 volumio-tv volumio[1340]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 20:24:33 volumio-tv volumio[1340]: TypeError: Cannot read properties of null (reading 'reset') Apr 11 20:24:33 volumio-tv volumio[1340]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Apr 11 20:24:33 volumio-tv volumio[1340]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Apr 11 20:24:33 volumio-tv volumio[1340]: at ChildProcess.emit (node:events:514:28) Apr 11 20:24:33 volumio-tv volumio[1340]: at maybeClose (node:internal/child_process:1105:16) Apr 11 20:24:33 volumio-tv volumio[1340]: at Socket. (node:internal/child_process:457:11) Apr 11 20:24:33 volumio-tv volumio[1340]: at Socket.emit (node:events:514:28) Apr 11 20:24:33 volumio-tv volumio[1340]: at Pipe. (node:net:337:12) Apr 11 20:24:33 volumio-tv volumio[1340]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 20:24:33 volumio-tv sudo[11888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 20:23' Apr 11 20:24:33 volumio-tv sudo[11888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"