-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Sat 2026-10-03 08:46:40 UTC. -- Oct 03 08:45:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 08:45:01 volumio volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 03 08:45:01 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:01 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 08:45:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 08:45:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 08:45:03 volumio volumio-remote-updater[580]: No test mode Oct 03 08:45:03 volumio volumio-remote-updater[580]: No alpha test mode Oct 03 08:45:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 03 08:45:04 volumio volumio[1107]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 08:45:04 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 08:45:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 08:45:07 volumio volumio[1107]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 03 08:45:07 volumio volumio[1107]: info: Completed starting MyVolumio Plugin Oct 03 08:45:07 volumio volumio[1107]: [Metrics] CommandRouter: 39s 698.87ms Oct 03 08:45:07 volumio volumio[1107]: info: CoreCommandRouter::volumiosetStartupVolume Oct 03 08:45:07 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 08:45:07 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 08:45:07 volumio volumio[1107]: info: CoreCommandRouter::Close All Modals sent Oct 03 08:45:07 volumio volumio[1107]: info: CoreCommandRouter::Close All Modals sent Oct 03 08:45:08 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 03 08:45:08 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 08:45:08 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 03 08:45:12 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Oct 03 08:45:12 volumio kernel: xhci_hcd 0000:01:00.0: @00000004295161a0 00000000 00000000 0e000000 02028000 Oct 03 08:45:14 volumio ntpd[734]: Soliciting pool server 172.236.195.26 Oct 03 08:45:14 volumio volumio[1107]: info: BOOT COMPLETED Oct 03 08:45:15 volumio ntpd[734]: Soliciting pool server 185.51.192.62 Oct 03 08:45:15 volumio ntpd[734]: Soliciting pool server 193.26.158.57 Oct 03 08:45:16 volumio ntpd[734]: Soliciting pool server 129.70.132.34 Oct 03 08:45:16 volumio ntpd[734]: Soliciting pool server 45.9.61.155 Oct 03 08:45:16 volumio ntpd[734]: Soliciting pool server 185.252.140.126 Oct 03 08:45:17 volumio ntpd[734]: Soliciting pool server 144.91.126.59 Oct 03 08:45:17 volumio ntpd[734]: Soliciting pool server 89.163.176.12 Oct 03 08:45:17 volumio ntpd[734]: Soliciting pool server 94.16.122.152 Oct 03 08:45:17 volumio ntpd[734]: Soliciting pool server 176.9.157.155 Oct 03 08:45:18 volumio ntpd[734]: Soliciting pool server 131.188.3.221 Oct 03 08:45:18 volumio ntpd[734]: Soliciting pool server 178.63.52.31 Oct 03 08:45:18 volumio ntpd[734]: Soliciting pool server 46.4.54.78 Oct 03 08:45:19 volumio ntpd[734]: Soliciting pool server 139.144.71.56 Oct 03 08:45:19 volumio ntpd[734]: Soliciting pool server 2a0e:b107:27d0:1::4 Oct 03 08:45:19 volumio ntpd[734]: Soliciting pool server 77.90.0.148 Oct 03 08:45:20 volumio ntpd[734]: Soliciting pool server 85.215.227.11 Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.123e16b8 does not match aorg 0000000000.00000000 from server@176.9.157.155 xmt 0xee6b3f21.c12c7afd Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.123c9475 does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xee6b3f21.c11c4776 Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.123f5f89 does not match aorg 0000000000.00000000 from server@185.252.140.126 xmt 0xee6b3f21.c15cc80a Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.1240c9ab does not match aorg 0000000000.00000000 from server@185.51.192.62 xmt 0xee6b3f21.c09a5d9e Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.1236fcc3 does not match aorg 0000000000.00000000 from server@85.215.227.11 xmt 0xee6b3f21.c2f32771 Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.123b56c1 does not match aorg 0000000000.00000000 from server@131.188.3.221 xmt 0xee6b3f21.c1c2c9bd Oct 03 08:45:21 volumio ntpd[734]: receive: Unexpected origin timestamp 0xee6b3f21.1239fcfe does not match aorg 0000000000.00000000 from server@46.4.54.78 xmt 0xee6b3f21.c124e6ae Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.497Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.243:55310 Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.497Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.243:58600 Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.552Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.419200921s timeout=20s Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.553Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.553Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.419881232s platform=PLATFORM_ANDROID version=6.260915.0 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 08:45:50 volumio volumio[1107]: info: Received Get System Info Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:50 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:50 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.561Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" name=Volumio Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.564Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" language=de Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.566Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" timezone=UTC Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.568Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" available=true connected=true macAddress=e4:5f:01:05:7e:ec ip4Address=192.168.1.212/24 ip6Address= Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.570Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" available=true connected=true macAddress=e4:5f:01:05:7e:ed ip4Address=192.168.1.214/24 ip6Address= ssid=WLAN_HER Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.571Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" setupComplete=true Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 08:45:50 volumio volumio[1107]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 03 08:45:50 volumio volumio[1107]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 03 08:45:50 volumio volumio[1107]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 03 08:45:50 volumio volumio[1107]: amixer -c 5 info | grep "E30" Oct 03 08:45:50 volumio volumio[1107]: Card hw:5 'E30'/'Topping E30 at usb-0000:01:00.0-1.4, high speed' Oct 03 08:45:50 volumio volumio[1107]: amixer -c 5 info | grep "E30" Oct 03 08:45:50 volumio volumio[1107]: Card hw:5 'E30'/'Topping E30 at usb-0000:01:00.0-1.4, high speed' Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.842Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" selectedOutputId=5 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:50 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:50 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:50 volumio volumio[1107]: info: Received Get System Info Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:50 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:50 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.871Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" currentVersion=3.912 latestVersion=3.912 Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.872Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" status=UPDATE_STATUS_NONE progress=0 Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.872Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" userId= Oct 03 08:45:50 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:50.872Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" providers=3 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 08:45:50 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.212:3000 from 192.168.1.243 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 08:45:50 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:51 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:51 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:51 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.214:3000 from 192.168.1.243 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.261Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" plugins=54 Oct 03 08:45:51 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:51 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.265Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" state=STATUS_STOPPED positionMs=0 volume=82 Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.266Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.780Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.421610936s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.830Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.866Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=http://pushupdates.volumio.org duration=34.214468ms Oct 03 08:45:51 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:51.960Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=129.073181ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.074Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://www.googleapis.com duration=243.82009ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.092Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=261.534279ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.108Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://securetoken.googleapis.com duration=277.180903ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.109Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://google.com duration=278.269537ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.118Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=285.421589ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.182Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://database.volumio.cloud duration=349.405178ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.196Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=363.982409ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.208Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://functions.volumio.cloud duration=376.702516ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.208Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=https://functions.volumio.cloud duration=376.309541ms Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.226Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=http://cddb.volumio.org duration=393.202129ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 08:45:52 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:52.380Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310 @ 0x2ac1080" latency=-2.425428567s timeout=10s endpoint=http://plugins.volumio.org duration=547.564842ms Oct 03 08:45:52 volumio sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 08:45:52 volumio sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 08:45:52 volumio sudo[1674]: pam_unix(sudo:session): session closed for user root Oct 03 08:45:52 volumio sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 08:45:52 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 08:45:52 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Oct 03 08:45:52 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.243 UA: Mozilla/5.0 (Linux; Android 17; Pixel 10a Build/CP3A.260905.009; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 03 08:45:52 volumio sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 08:45:52 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 08:45:52 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Oct 03 08:45:52 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 08:45:52 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 08:45:52 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Oct 03 08:45:52 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.243 UA: Mozilla/5.0 (Linux; Android 17; Pixel 10a Build/CP3A.260905.009; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:52 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 08:45:52 volumio volumio[1107]: info: Received Get System Info Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:52 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:52 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:52 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:52 volumio volumio[1107]: info: Listing playlists Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 08:45:52 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 08:45:53 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:45:54 volumio volumio[1107]: info: Received Get System Info Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:54 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:54 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:54 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 03 08:45:55 volumio volumio[1107]: info: Received Get System Version Oct 03 08:45:55 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 08:45:56 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:45:56 volumio volumio[1107]: info: Received Get System Info Oct 03 08:45:56 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:45:56 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:45:56 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:45:56 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:45:56 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:45:56 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:45:57 volumio volumio5-onboarding[1293]: time=2026-10-03T08:45:57.024Z level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Oct 03 08:45:57 volumio dbus-daemon[605]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=1293 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=732 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Oct 03 08:45:58 volumio volumio-remote-updater[580]: No test mode Oct 03 08:45:58 volumio volumio-remote-updater[580]: No alpha test mode Oct 03 08:45:58 volumio volumio[1107]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 08:45:58 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 08:45:59 volumio volumio[1107]: info: CoreCommandRouter::Close All Modals sent Oct 03 08:46:02 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.364Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.367Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=3.109551ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.379Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=14.909041ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.381Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=http://pushupdates.volumio.org duration=16.796756ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.383Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=19.072318ms Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:46:03 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:46:03 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:46:03 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.214:3000 from 192.168.1.243 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 08:46:03 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.454Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://google.com duration=89.860018ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.481Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://www.googleapis.com duration=116.566105ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.500Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=136.482782ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.508Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://functions.volumio.cloud duration=143.836276ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.509Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://functions.volumio.cloud duration=144.585489ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.511Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://database.volumio.cloud duration=147.136287ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.516Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=https://securetoken.googleapis.com duration=151.778818ms Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.562Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=http://cddb.volumio.org duration=198.443526ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 08:46:03 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:03.680Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.419854893s timeout=10s endpoint=http://plugins.volumio.org duration=315.347866ms Oct 03 08:46:04 volumio volumio[1107]: info: CoreCommandRouter::volumioPlay Oct 03 08:46:04 volumio volumio[1107]: info: CoreStateMachine::play index undefined Oct 03 08:46:04 volumio volumio[1107]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 08:46:04 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:04 volumio volumio[1107]: info: CoreStateMachine::startPlaybackTimer Oct 03 08:46:04 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:04 volumio volumio[1107]: info: [1791017164345] ControllerWebradio::clearAddPlayTrack Oct 03 08:46:04 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 08:46:04 volumio volumio[1107]: info: sendMpdCommand stop took 2 milliseconds Oct 03 08:46:04 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 08:46:04 volumio volumio[1107]: info: sendMpdCommand clear took 0 milliseconds Oct 03 08:46:04 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" Oct 03 08:46:04 volumio volumio[1107]: info: Oct 03 08:46:04 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:04 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:04 volumio volumio[1107]: info: Oct 03 08:46:04 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:04 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:04 volumio volumio[1107]: info: Oct 03 08:46:04 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:04 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:04 volumio volumio[1107]: error: Upnp client error: Error: This socket has been ended by the other party Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" Oct 03 08:46:06 volumio volumio[1107]: error: updateQueue error: null Oct 03 08:46:06 volumio volumio[1107]: error: updateQueue error: null Oct 03 08:46:06 volumio volumio[1107]: error: updateQueue error: null Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 1886ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 1884ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 1883ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand add "https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" took 3 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand play Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 5ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand play took 3 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 4ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 3ms Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 8 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 8 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 7 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 5 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus stop Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 43ms Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.833Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=0 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.833Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=0 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=0 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.836Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.837Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=0 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 60ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 59ms Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 54ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 53 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 7 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 6 milliseconds Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.866Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.867Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.868Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.868Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 79ms Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:06 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:06 volumio volumio[1107]: info: Oct 03 08:46:06 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 11ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 10 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 10ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand status took 9 milliseconds Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.909Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.910Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.913Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.913Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.913Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.914Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.913Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.914Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.914Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.918Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.918Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 130ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 84ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 84ms Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 03 08:46:06 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:06 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:06 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:06 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:06 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:06 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.949Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.950Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.950Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.952Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.952Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.952Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=253 volume=82 Oct 03 08:46:06 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:06.953Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 88ms Oct 03 08:46:06 volumio volumio[1107]: info: ------------------------------ 87ms Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:08 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:08 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:08 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:08 volumio volumio[1107]: info: Oct 03 08:46:08 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 9ms Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand status took 7 milliseconds Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 6ms Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand status took 6 milliseconds Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 6ms Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand status took 5 milliseconds Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:08 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:08 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:08 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:08 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.192Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.193Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.193Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 35ms Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 03 08:46:08 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:08 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:08 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:08 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:08 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:08 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:08 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:08 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Absolut relax","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:08 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:08 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:08 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.224Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.224Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.225Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.225Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.226Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.227Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.228Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=1505 volume=82 Oct 03 08:46:08 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:08.228Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 73ms Oct 03 08:46:08 volumio volumio[1107]: info: ------------------------------ 71ms Oct 03 08:46:09 volumio volumio[1107]: info: VolumeController::SetAlsaVolume74 Oct 03 08:46:09 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:09 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:09 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:09 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:09.479Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=2756 volume=74 Oct 03 08:46:09 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:09.480Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="Absolut relax" Oct 03 08:46:09 volumio volumio[1107]: info: Setting Spotify Volume from Volumio: 74 Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 08:46:10 volumio volumio[1107]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 03 08:46:10 volumio volumio[1107]: info: Received Get System Version Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 08:46:10 volumio volumio[1107]: info: Received Get System Info Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:46:10 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:46:10 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:46:10 volumio volumio[1107]: info: Sending Spotify command with payload to local API: /player/volume Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.242Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.252Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=9.144062ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.261Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=18.104831ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.262Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=http://pushupdates.volumio.org duration=18.291588ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.263Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=19.737828ms Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 08:46:11 volumio volumio[1107]: info: Discovery: Getting this device information Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 08:46:11 volumio volumio[1107]: verbose: New Socket.io Connection to 192.168.1.214:3000 from 192.168.1.243 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 08:46:11 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.301Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://google.com duration=58.428716ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.356Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://www.googleapis.com duration=112.773172ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.381Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=137.512378ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.387Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://functions.volumio.cloud duration=143.984901ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.388Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://functions.volumio.cloud duration=144.20338ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.397Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://database.volumio.cloud duration=153.267443ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.412Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=https://securetoken.googleapis.com duration=169.26897ms Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.441Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=http://cddb.volumio.org duration=197.566886ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 08:46:11 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:11.578Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" latency=-2.423230058s timeout=10s endpoint=http://plugins.volumio.org duration=334.206553ms Oct 03 08:46:13 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:46:13 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 08:46:19 volumio volumio[1107]: info: Enabling plugin remotepi Oct 03 08:46:19 volumio volumio[1107]: info: Loading plugin "remotepi"... Oct 03 08:46:19 volumio volumio[1107]: info: Applying required configuration parameters for plugin remotepi Oct 03 08:46:19 volumio volumio[1107]: info: PLUGIN START: remotepi Oct 03 08:46:19 volumio volumio[1107]: info: Loading i18n strings for locale de Oct 03 08:46:19 volumio volumio[1107]: info: Error: Error: EINVAL: invalid argument, write Oct 03 08:46:19 volumio kernel: export_store: invalid GPIO 14 Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:25 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:25 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:25 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:25 volumio volumio[1107]: info: Oct 03 08:46:25 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 6ms Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand status took 5 milliseconds Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 4ms Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand status took 3 milliseconds Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 4ms Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand status took 3 milliseconds Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:25 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:25 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:25 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:25 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19730,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:25 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:25 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:25 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:25 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19730,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:25 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:25 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:25 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:25 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19730,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:25 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:25 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:25 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.659Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.660Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.661Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.662Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.665Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.665Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.666Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.666Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.667Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.667Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.667Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=19023 volume=74 Oct 03 08:46:25 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:25.668Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 54ms Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 52ms Oct 03 08:46:25 volumio volumio[1107]: info: ------------------------------ 51ms Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 03 08:46:26 volumio volumio[1107]: info: Received Get System Version Oct 03 08:46:26 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:28 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:28 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces system playlist update Oct 03 08:46:28 volumio volumio[1107]: info: Ignoring MPD Status Update Oct 03 08:46:28 volumio volumio[1107]: info: Oct 03 08:46:28 volumio volumio[1107]: ---------------------------- MPD announces state update: player Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::getState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand status Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 5ms Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand status took 5 milliseconds Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 4ms Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand status took 3 milliseconds Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 3ms Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand status took 2 milliseconds Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseState Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:28 volumio volumio[1107]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:28 volumio volumio[1107]: verbose: ControllerMpd::parseTrackInfo Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:28 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:28 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:28 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:28 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:28 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:28 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:28 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:28 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio[1107]: info: ControllerMpd::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::servicePushState Oct 03 08:46:28 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Oct 03 08:46:28 volumio volumio[1107]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BIRDY - PEOPLE HELP THE PEOPLE","artist":"Absolut relax","album":null,"uri":"https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn","trackType":""} Oct 03 08:46:28 volumio volumio[1107]: verbose: CURRENT POSITION 0 Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState stateService play Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::syncState currentStatus play Oct 03 08:46:28 volumio volumio[1107]: info: Received an update from plugin. extracting info from payload Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreStateMachine::pushState Oct 03 08:46:28 volumio volumio[1107]: info: CoreCommandRouter::volumioPushState Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.331Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.331Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.333Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.333Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.334Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.334Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.335Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.335Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.336Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.336Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.337Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" state=STATUS_PLAYING positionMs=21539 volume=74 Oct 03 08:46:28 volumio volumio5-onboarding[1293]: time=2026-10-03T08:46:28.337Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.243:58600,192.168.1.243:55310,00:00:00:00:00:00%01 @ 0x2ac1080" id="https://absolut-relax.live-sm.absolutradio.de/absolut-relax/stream/mp3?aggregator=TuneIn" title="BIRDY - PEOPLE HELP THE PEOPLE" Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 58ms Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 57ms Oct 03 08:46:28 volumio volumio[1107]: info: ------------------------------ 55ms Oct 03 08:46:29 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:46:29 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 08:46:33 volumio volumio[1107]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 08:46:36 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 08:46:36 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 08:46:39 volumio volumio[1107]: info: Starting Uninstall of plugin system_hardware - remotepi Oct 03 08:46:39 volumio volumio[1107]: info: Uninstalling plugin remotepi Oct 03 08:46:39 volumio volumio[1107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 08:46:39 volumio volumio[1107]: TypeError: Cannot read property 'unwatchAll' of undefined Oct 03 08:46:39 volumio volumio[1107]: at remotepi.onStop (/data/plugins/system_hardware/remotepi/index.js:72:16) Oct 03 08:46:39 volumio volumio[1107]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 03 08:46:39 volumio volumio[1107]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 03 08:46:39 volumio volumio[1107]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Oct 03 08:46:39 volumio volumio[1107]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1271:45) Oct 03 08:46:39 volumio volumio[1107]: at Socket.emit (events.js:315:20) Oct 03 08:46:39 volumio volumio[1107]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 03 08:46:39 volumio volumio[1107]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 03 08:46:39 volumio volumio[1107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 08:46:40 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-03 08:45 Oct 03 08:46:40 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"