Mar 28 18:51:04 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:04.469+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:04 car-pc volumio[2058]: info: Checking if install.sh is present Mar 28 18:51:04 car-pc volumio[2058]: info: Executing install.sh Mar 28 18:51:04 car-pc sudo[10677]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/touch_display/install.sh Mar 28 18:51:04 car-pc sudo[10677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:04 car-pc volumio[2058]: info: Re-synchronizing package index files from their sources Mar 28 18:51:04 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:04.549+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:04 car-pc volumio[2058]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Mar 28 18:51:04 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:04.774+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:04 car-pc volumio[2058]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Mar 28 18:51:04 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:04.785+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:08 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:08 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:08.448+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:08 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:08 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:08.620+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:09 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:09 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:09.624+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:09 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:09 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:09.627+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:10 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:10 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:10.819+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:10 car-pc volumio[2058]: info: Installing graphical environment Mar 28 18:51:10 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:10.824+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:10 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:10 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:10.918+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:11 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:11 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:11.395+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:11 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:11 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:11.399+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:12 car-pc volumio[2058]: info: x11-utils is already the newest version (7.7+5). Mar 28 18:51:12 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:12.424+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:12 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:12 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:12.427+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:12 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:12 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:12.523+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:13 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:13 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:13.001+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:13 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:13 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:13.005+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:14 car-pc volumio[2058]: info: xinit is already the newest version (1.4.0-1). Mar 28 18:51:14 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:14.023+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:14 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:14 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:14.027+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:14 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:14 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:14.125+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:14 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:14 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:14.607+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:14 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:14 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:14.611+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:15 car-pc volumio[2058]: info: xorg is already the newest version (1:7.7+23+b1). Mar 28 18:51:15 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:15.645+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:15 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:15 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:15.647+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:15 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:15 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:15.745+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:16 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:16.220+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:16 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:16.223+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:16.600+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.60:37988,00:00:00:00:00:00%02,192.168.0.8:47234 @ 0x25367e0" latency=26.685401ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 28 18:51:17 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:17.918+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.8:47234 Mar 28 18:51:17 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:17.918+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.8:47234 Mar 28 18:51:17 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:17.928+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.8:53604 Mar 28 18:51:18 car-pc volumio[2058]: info: xinput is already the newest version (1.6.3-1). Mar 28 18:51:18 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:18.237+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:18 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:18 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:18.239+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:18 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:18 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:18.338+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:18 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:18 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:18.814+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:18 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:18 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:18.817+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:19 car-pc volumio[2058]: info: openbox is already the newest version (3.6.1-10+rpt1). Mar 28 18:51:19 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:19.849+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:19 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:19 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:19.852+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:19 car-pc volumio[2058]: info: Creating /etc/X11/xorg.conf.d dir Mar 28 18:51:19 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:19.857+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:19 car-pc volumio[2058]: info: Creating Xorg configuration Mar 28 18:51:19 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:19.860+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:19 car-pc volumio[2058]: info: Installing Chromium Mar 28 18:51:19 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:19.864+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:20 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:20.383+01:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Mar 28 18:51:21 car-pc sudo[10878]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 18:51:21 car-pc sudo[10878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:21 car-pc sudo[10878]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:21 car-pc sudo[10880]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:21 car-pc sudo[10880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:21 car-pc sudo[10880]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:21 car-pc volumio[2058]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S721B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 28 18:51:21 car-pc sudo[10885]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 18:51:21 car-pc sudo[10885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:21 car-pc sudo[10885]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:21 car-pc sudo[10887]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:21 car-pc sudo[10887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:21 car-pc sudo[10887]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:21 car-pc volumio[2058]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S721B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:21 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 18:51:21 car-pc volumio[2058]: info: Received Get System Info Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:21 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:21 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:21 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:21 car-pc volumio[2058]: info: Listing playlists Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:51:21 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:23 car-pc volumio[2058]: info: Received Get System Info Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:23 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:23 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:23 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 18:51:24 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:24 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:24.997+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:25 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:25 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:25.491+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:25 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:25 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:25.493+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:26 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:26 car-pc volumio[2058]: info: Received Get System Info Mar 28 18:51:26 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:26 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:26 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:26 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:26 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:26 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:27 car-pc volumio[2058]: info: Received Get System Info Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:27 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:27 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:27 car-pc volumio[2058]: info: chromium-browser is already the newest version (1:146.0.7680.164-1~deb12u1+rpt1). Mar 28 18:51:27 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:27.534+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:27 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:27 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:27.537+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:27 car-pc volumio[2058]: info: Installing fonts Mar 28 18:51:27 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:27.541+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:27 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:27 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:27 car-pc volumio[2058]: info: Reading package lists... Mar 28 18:51:27 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:27.637+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:27 car-pc volumio[2058]: verbose: New Socket.io Connection to 192.168.0.102:3000 from 192.168.0.8 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 18:51:27 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 18:51:28 car-pc volumio[2058]: info: Building dependency tree... Mar 28 18:51:28 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:28.127+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:28 car-pc volumio[2058]: info: Reading state information... Mar 28 18:51:28 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:28.129+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-arphic-ukai is already the newest version (0.2.20080216.2-5). Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.153+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-arphic-gbsn00lp is already the newest version (2.11-16). Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-unfonts-core is already the newest version (1:1.0.2-080608-18). Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-ipafont is already the newest version (00303-23). Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-vlgothic is already the newest version (20220612-1). Mar 28 18:51:29 car-pc volumio[2058]: info: fonts-thai-tlwg-ttf is already the newest version (1:0.7.3-1). Mar 28 18:51:29 car-pc volumio[2058]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.159+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.160+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.161+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.167+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.167+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.167+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio[2058]: info: Creating Kiosk data dir Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.169+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio[2058]: info: Creating Volumio kiosk start script Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.171+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc volumio[2058]: info: Creating Systemd Unit for Kiosk Mar 28 18:51:29 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:29.187+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:29 car-pc systemd[1]: Reloading. Mar 28 18:51:30 car-pc volumio[2058]: info: Installing Virtual Keyboard Mar 28 18:51:30 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:30.112+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:30 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetQueue Mar 28 18:51:30 car-pc volumio[2058]: info: CoreStateMachine::getQueue Mar 28 18:51:30 car-pc volumio[2058]: info: CorePlayQueue::getQueue Mar 28 18:51:31 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 18:51:33 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:33 car-pc volumio[2058]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::ClearQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::clearPlayQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::addQueueItems Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::addQueueItems Mar 28 18:51:33 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:33 car-pc volumio[2058]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Mar 28 18:51:33 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 18:51:33 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:33 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::play index 0 Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:33 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:33 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:33 car-pc volumio[2058]: info: [1774720293164] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:33 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:33 car-pc volumio[2058]: info: sendMpdCommand stop took 2 milliseconds Mar 28 18:51:33 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:33 car-pc volumio[2058]: info: Mar 28 18:51:33 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:33 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:33 car-pc volumio[2058]: info: sendMpdCommand clear took 2 milliseconds Mar 28 18:51:33 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:33 car-pc volumio[2058]: info: Mar 28 18:51:33 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:33 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:33 car-pc volumio[2058]: info: Mar 28 18:51:33 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:33 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:33 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:33 car-pc volumio[2058]: info: ------------------------------ 7ms Mar 28 18:51:34 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:34 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:34 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:34 car-pc volumio[2058]: info: ------------------------------ 1524ms Mar 28 18:51:34 car-pc volumio[2058]: info: ------------------------------ 1523ms Mar 28 18:51:34 car-pc volumio[2058]: info: Mar 28 18:51:34 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:34 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 4 milliseconds Mar 28 18:51:34 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:34 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:34 car-pc volumio[2058]: info: Mar 28 18:51:34 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:34 car-pc volumio[2058]: info: Mar 28 18:51:34 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:34 car-pc volumio[2058]: info: ------------------------------ 8ms Mar 28 18:51:34 car-pc volumio[2058]: info: sendMpdCommand play took 6 milliseconds Mar 28 18:51:34 car-pc volumio[2058]: info: ------------------------------ 5ms Mar 28 18:51:34 car-pc volumio[2058]: info: ------------------------------ 5ms Mar 28 18:51:35 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:35 car-pc volumio[2058]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::ClearQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::clearPlayQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::addQueueItems Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::addQueueItems Mar 28 18:51:35 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:35 car-pc volumio[2058]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Mar 28 18:51:35 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 18:51:35 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:35 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::play index 0 Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:35 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 car-pc volumio[2058]: info: [1774720295565] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:35 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:37 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:37 car-pc volumio[2058]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::ClearQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::clearPlayQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::addQueueItems Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::addQueueItems Mar 28 18:51:37 car-pc volumio[2058]: info: Preload queue cleared Mar 28 18:51:37 car-pc volumio[2058]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Mar 28 18:51:37 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 18:51:37 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::saveQueue Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:37 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::play index 0 Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::stop Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:37 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:37 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:37 car-pc volumio[2058]: info: [1774720297963] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:37 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:51:39 car-pc volumio[2058]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 18:51:39 car-pc volumio[2058]: info: Received Get System Version Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 18:51:39 car-pc volumio[2058]: info: Received Get System Info Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:39 car-pc volumio[2058]: info: Discovery: Getting this device information Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:39 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:39 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:42 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:42 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:42 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:42 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:42 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:42 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:42 car-pc volumio[2058]: info: [1774720302368] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:42 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:43 car-pc volumio[2058]: info: Allowing volumio to start an xsession Mar 28 18:51:43 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:43.627+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:44 car-pc volumio[2058]: info: Configuring GPU/DRI permissions for volumio user Mar 28 18:51:44 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:44.281+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand stop took 8786 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand stop took 6398 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand stop took 1993 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand clear took 9 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand status took 122 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:44 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:44.492+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2401ce0" state=STATUS_STOPPED positionMs=2097 volume=8 Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 138ms Mar 28 18:51:44 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:44 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand status took 254 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 257ms Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand clear took 253 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand clear took 253 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand status took 251 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 250ms Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:44 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:44 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:44 car-pc volumio[2058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:44 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:44.632+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2401ce0" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:44 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:44.633+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2401ce0" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 276ms Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 269ms Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 19 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 23ms Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 248ms Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 247ms Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand play took 246 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 246ms Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 5 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 5 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:44 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:44 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 8ms Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand play took 5 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: sendMpdCommand play took 5 milliseconds Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 4ms Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 3ms Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: Mar 28 18:51:44 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:44 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 1ms Mar 28 18:51:44 car-pc volumio[2058]: info: ------------------------------ 2ms Mar 28 18:51:46 car-pc volumio[2058]: info: Creating udev rules for persistent DRI device permissions Mar 28 18:51:46 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:46.934+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:46 car-pc volumio[2058]: info: Reloading udev rules Mar 28 18:51:46 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:46.947+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:47 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:47 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:47 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:47 car-pc volumio[2058]: info: [1774720307297] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:47 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:47 car-pc volumio[2058]: info: Creating Mesa shader cache directory Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.610+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:47 car-pc sudo[10677]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:47 car-pc volumio[2058]: Plugin install end detected on script Mar 28 18:51:47 car-pc volumio[2058]: info: Install script completed Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.622+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:47 car-pc volumio[2058]: info: Adding reference to registry Mar 28 18:51:47 car-pc volumio[2058]: info: Done installing plugin. Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.629+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:47 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:47 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 18:51:47 car-pc volumio[2058]: info: Folder /tmp/plugins removed Mar 28 18:51:47 car-pc volumio[2058]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 18:51:47 car-pc volumio[2058]: info: Folder /data/temp removed Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.837+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=4 index=0 tries=11 Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.837+01:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2401ce0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.838+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_STOPPED positionMs=2097 volume=8 Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.838+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988,00:00:00:00:00:00%02 @ 0x25367e0" state=STATUS_STOPPED positionMs=2097 volume=8 Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.838+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:47234 @ 0x24a8180" state=STATUS_STOPPED positionMs=2097 volume=8 Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.838+01:00 level=ERROR msg="failed to send event" component=server dst="192.168.0.8:47234 @ 0x24a8180" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.0.8:47234" Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.838+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_STOPPED positionMs=2097 volume=8 Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.839+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.839+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988,00:00:00:00:00:00%02 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:47 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:47.839+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:47 car-pc systemd-logind[828]: Watching system buttons on /dev/input/event2 (vc4-hdmi-1) Mar 28 18:51:47 car-pc volumio[2058]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::play index undefined Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:47 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:47 car-pc volumio[2058]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:47 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:47 car-pc volumio[2058]: info: [1774720307950] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:47 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:47 car-pc systemd-logind[828]: Watching system buttons on /dev/input/event0 (vc4-hdmi-0) Mar 28 18:51:51 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:51.446+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=4 index=0 tries=11 Mar 28 18:51:55 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:55.394+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=26 index=0 tries=11 Mar 28 18:51:55 car-pc volumio[2058]: info: Enabling plugin touch_display Mar 28 18:51:55 car-pc volumio[2058]: info: Loading plugin "touch_display"... Mar 28 18:51:56 car-pc volumio[2058]: info: PLUGIN START: touch_display Mar 28 18:51:56 car-pc volumio[2058]: info: Loading i18n strings for locale de Mar 28 18:51:56 car-pc sudo[11082]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 28 18:51:56 car-pc sudo[11082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:56 car-pc sudo[11082]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 18:51:57 car-pc sudo[11085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 28 18:51:57 car-pc sudo[11085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:57 car-pc sudo[11087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 28 18:51:57 car-pc sudo[11087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:57 car-pc sudo[11090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 28 18:51:57 car-pc sudo[11090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:57 car-pc systemd[1]: Stopping getty@tty1.service - Getty on tty1... Mar 28 18:51:57 car-pc systemd[1]: getty@tty1.service: Deactivated successfully. Mar 28 18:51:57 car-pc systemd[1]: Stopped getty@tty1.service - Getty on tty1. Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand stop took 9993 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand stop took 9341 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:57 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:51:57 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:57 car-pc volumio[2058]: info: touch_display: No backlight interface detected. Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand status took 27 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand clear took 27 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand clear took 26 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand status took 24 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand status took 23 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:51:57 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:51:57 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:51:57 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:57 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:57 car-pc volumio[2058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:57 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:51:57 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:51:57 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:57 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:57 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:57 car-pc volumio[2058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.329+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 36ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 35ms Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.331+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.331+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.332+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.333+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.333+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_STOPPED positionMs=9312 volume=8 Mar 28 18:51:57 car-pc sudo[11095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 28 18:51:57 car-pc sudo[11095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:57 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:57 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.334+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.339+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.339+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 22ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 22ms Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.337+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.341+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.341+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:57 car-pc volumio[2058]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 53ms Mar 28 18:51:57 car-pc sudo[11095]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:57 car-pc volumio[2058]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:51:57 car-pc volumio[2058]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 28 18:51:57 car-pc volumio[2058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 28 18:51:57 car-pc sudo[11098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 28 18:51:57 car-pc sudo[11098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:57 car-pc sudo[11098]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:57 car-pc volumio[2058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:57 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:57 car-pc volumio[2058]: error: updateQueue error: null Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 261ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 261ms Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 120 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 3 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:57 car-pc volumio[2058]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:57 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 8ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 9ms Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand play took 7 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: sendMpdCommand play took 6 milliseconds Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 5ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 4ms Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: Mar 28 18:51:57 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:51:57 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 2ms Mar 28 18:51:57 car-pc volumio[2058]: info: ------------------------------ 2ms Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.802+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=4 index=0 tries=11 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.802+01:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2401ce0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.802+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.802+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988,00:00:00:00:00:00%02 @ 0x25367e0" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.803+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:47234 @ 0x24a8180" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.803+01:00 level=ERROR msg="failed to send event" component=server dst="192.168.0.8:47234 @ 0x24a8180" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.0.8:47234" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.803+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:51:57 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:51:57.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.112+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=4 index=0 tries=11 Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.112+01:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2401ce0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.112+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.113+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988,00:00:00:00:00:00%02 @ 0x25367e0" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.113+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:47234 @ 0x24a8180" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.113+01:00 level=ERROR msg="failed to send event" component=server dst="192.168.0.8:47234 @ 0x24a8180" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.0.8:47234" Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.113+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_STOPPED positionMs=2206 volume=8 Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.113+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.114+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:52:01 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:01.114+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Mar 28 18:52:07 car-pc volumio[2058]: info: Enabling plugin touch_display Mar 28 18:52:07 car-pc volumio[2058]: info: Loading plugin "touch_display"... Mar 28 18:52:07 car-pc volumio[2058]: info: Applying required configuration parameters for plugin touch_display Mar 28 18:52:07 car-pc volumio[2058]: info: PLUGIN START: touch_display Mar 28 18:52:07 car-pc volumio[2058]: info: Loading i18n strings for locale de Mar 28 18:52:07 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 18:52:07 car-pc sudo[11117]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 28 18:52:07 car-pc sudo[11117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc sudo[11119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 28 18:52:07 car-pc sudo[11119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc sudo[11117]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:07 car-pc volumio[2058]: info: touch_display: No backlight interface detected. Mar 28 18:52:07 car-pc sudo[11125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 28 18:52:07 car-pc sudo[11125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc sudo[11124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 28 18:52:07 car-pc sudo[11124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc volumio[2058]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 28 18:52:07 car-pc sudo[11128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 28 18:52:07 car-pc sudo[11128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc sudo[11132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 28 18:52:07 car-pc sudo[11132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:07 car-pc sudo[11132]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:07 car-pc volumio[2058]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 28 18:52:07 car-pc sudo[11128]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:07 car-pc volumio[2058]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 28 18:52:07 car-pc kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:100:pixelvalve-2] flip_done timed out Mar 28 18:52:10 car-pc volumio[2058]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 18:52:10 car-pc volumio[2058]: info: touch_display: About to detect HDMI ports... Mar 28 18:52:10 car-pc volumio[2058]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Mar 28 18:52:16 car-pc systemd[1]: Reloading. Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 80 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:52:16 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 81 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 80 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 3 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 99ms Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces state update: player Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::getState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 21ms Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 4 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand status took 3 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseState Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:52:16 car-pc volumio[2058]: info: Received an update from plugin. extracting info from payload Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:52:16 car-pc volumio[2058]: info: Received an update from plugin. extracting info from payload Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:52:16 car-pc volumio[2058]: info: Received an update from plugin. extracting info from payload Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.844+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.844+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.844+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.844+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.845+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.845+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.847+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.848+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.849+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.849+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.849+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.849+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.849+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.850+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.850+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.850+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.851+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.851+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.851+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.852+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.852+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.852+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.857+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.857+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.858+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.859+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.859+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.859+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.860+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.860+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.860+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 141ms Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 141ms Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 65ms Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:52:16 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:52:16 car-pc volumio[2058]: info: Mar 28 18:52:16 car-pc volumio[2058]: ---------------------------- MPD announces system playlist update Mar 28 18:52:16 car-pc volumio[2058]: info: Ignoring MPD Status Update Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 2ms Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 2ms Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:52:16 car-pc volumio[2058]: info: Received an update from plugin. extracting info from payload Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: ControllerMpd::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::servicePushState Mar 28 18:52:16 car-pc volumio[2058]: info: CorePlayQueue::getTrack 0 Mar 28 18:52:16 car-pc volumio[2058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"My Favorite Strings - West Mount Airy","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Mar 28 18:52:16 car-pc volumio[2058]: verbose: CURRENT POSITION 0 Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState stateService play Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:52:16 car-pc volumio[2058]: info: Received an update from plugin. extracting info from payload Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreStateMachine::pushState Mar 28 18:52:16 car-pc volumio[2058]: info: CoreCommandRouter::volumioPushState Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.873+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.873+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.874+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.874+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.874+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.874+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.874+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.875+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.875+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.875+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.875+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.875+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.877+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.877+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.877+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.877+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" state=STATUS_PLAYING positionMs=0 volume=8 Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x2850030" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.60:37988 @ 0x25367e0" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio5-onboarding[1497]: time=2026-03-28T18:52:16.878+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.8:53604,00:00:00:00:00:00%03 @ 0x24a9290" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="My Favorite Strings - West Mount Airy" Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 61ms Mar 28 18:52:16 car-pc volumio[2058]: info: ------------------------------ 61ms Mar 28 18:52:17 car-pc sudo[11090]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:17 car-pc volumio[2058]: info: touch_display: systemctl daemon-reload succeeded. Mar 28 18:52:17 car-pc systemd[1]: Reloading. Mar 28 18:52:17 car-pc sudo[11170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 28 18:52:17 car-pc sudo[11170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:18 car-pc sudo[11087]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:18 car-pc volumio[2058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 28 18:52:18 car-pc sudo[11085]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:18 car-pc systemd[1]: Reloading. Mar 28 18:52:18 car-pc volumio[2058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 28 18:52:19 car-pc sudo[11125]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:19 car-pc volumio[2058]: info: touch_display: systemctl daemon-reload succeeded. Mar 28 18:52:19 car-pc sudo[11207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 28 18:52:19 car-pc sudo[11207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:52:19 car-pc systemd[1]: Reloading. Mar 28 18:52:20 car-pc sudo[11124]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 28 18:52:20 car-pc systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 28 18:52:20 car-pc sudo[11207]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:20 car-pc sudo[11170]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: Volumio Kiosk started. Mar 28 18:52:20 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:52:20 car-pc sudo[11119]: pam_unix(sudo:session): session closed for user root Mar 28 18:52:20 car-pc volumio[2058]: info: Done. Mar 28 18:52:20 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: Volumio Kiosk started. Mar 28 18:52:20 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:52:20 car-pc volumio[2058]: info: Done. Mar 28 18:52:20 car-pc volumio[2058]: info: CoreCommandRouter::volumioGetState Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: X display number found: Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: X display number found: Mar 28 18:52:20 car-pc startx[11246]: xauth: file /home/volumio/.Xauthority does not exist Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: X display number found: 0 Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: X display number found: 0 Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: X display number found: 0 Mar 28 18:52:20 car-pc startx[11253]: X.Org X Server 1.21.1.7 Mar 28 18:52:20 car-pc startx[11253]: X Protocol Version 11, Revision 0 Mar 28 18:52:20 car-pc startx[11253]: Current Operating System: Linux car-pc 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Mar 28 18:52:20 car-pc startx[11253]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:1280x800M@60D numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=DC:A6:32:FC:15:EC vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=19641eb1-2760-4b10-b19b-739846da006e uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 28 18:52:20 car-pc startx[11253]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 28 18:52:20 car-pc startx[11253]: Current version of pixman: 0.44.0 Mar 28 18:52:20 car-pc startx[11253]: Before reporting problems, check http://wiki.x.org Mar 28 18:52:20 car-pc startx[11253]: to make sure that you have the latest version. Mar 28 18:52:20 car-pc startx[11253]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 28 18:52:20 car-pc startx[11253]: (++) from command line, (!!) notice, (II) informational, Mar 28 18:52:20 car-pc startx[11253]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 28 18:52:20 car-pc startx[11253]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Mar 28 18:52:20 2026 Mar 28 18:52:20 car-pc startx[11253]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 28 18:52:20 car-pc startx[11253]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 28 18:52:20 car-pc volumio[2058]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 28 18:52:20 car-pc volumio[2058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:52:20 car-pc volumio[2058]: Error: connect EISCONN /tmp/.X11-unix/X0 Mar 28 18:52:20 car-pc volumio[2058]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 28 18:52:20 car-pc volumio[2058]: errno: -106, Mar 28 18:52:20 car-pc volumio[2058]: code: 'EISCONN', Mar 28 18:52:20 car-pc volumio[2058]: syscall: 'connect', Mar 28 18:52:20 car-pc volumio[2058]: address: '/tmp/.X11-unix/X0' Mar 28 18:52:20 car-pc volumio[2058]: } Mar 28 18:52:20 car-pc volumio[2058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:52:21 car-pc sudo[11285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 18:51' Mar 28 18:52:21 car-pc sudo[11285]: 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"