-- Logs begin at Fri 2026-10-16 02:18:57 CEST, end at Fri 2026-10-16 16:14:55 CEST. -- Oct 16 16:13:00 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:00 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:00 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:00 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:00 volumio volumio[671]: info: Listing playlists Oct 16 16:13:00 volumio volumio[671]: info: Listing playlists Oct 16 16:13:00 volumio volumio[671]: info: Listing playlists Oct 16 16:13:00 volumio volumio[671]: info: Listing playlists Oct 16 16:13:01 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:01 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:01 volumio volumio[671]: info: Listing playlists Oct 16 16:13:01 volumio volumio[671]: info: Listing playlists Oct 16 16:13:10 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:10 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:10 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:10 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:11 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:11 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:20 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:20 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:20 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:20 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:20 volumio volumio[671]: info: Listing playlists Oct 16 16:13:20 volumio volumio[671]: info: Listing playlists Oct 16 16:13:20 volumio volumio[671]: info: Listing playlists Oct 16 16:13:20 volumio volumio[671]: info: Listing playlists Oct 16 16:13:21 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:21 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:21 volumio volumio[671]: info: Listing playlists Oct 16 16:13:21 volumio volumio[671]: info: Listing playlists Oct 16 16:13:30 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:30 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:30 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:30 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:31 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:31 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:40 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:40 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:40 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:40 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:40 volumio volumio[671]: info: Listing playlists Oct 16 16:13:40 volumio volumio[671]: info: Listing playlists Oct 16 16:13:40 volumio volumio[671]: info: Listing playlists Oct 16 16:13:40 volumio volumio[671]: info: Listing playlists Oct 16 16:13:41 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:41 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:41 volumio volumio[671]: info: Listing playlists Oct 16 16:13:41 volumio volumio[671]: info: Listing playlists Oct 16 16:13:50 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:50 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:50 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:50 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:13:51 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:13:51 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:00 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:00 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:00 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:00 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:00 volumio volumio[671]: info: Listing playlists Oct 16 16:14:00 volumio volumio[671]: info: Listing playlists Oct 16 16:14:00 volumio volumio[671]: info: Listing playlists Oct 16 16:14:00 volumio volumio[671]: info: Listing playlists Oct 16 16:14:01 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:01 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:01 volumio volumio[671]: info: Listing playlists Oct 16 16:14:01 volumio volumio[671]: info: Listing playlists Oct 16 16:14:05 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:05.925+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:46522 Oct 16 16:14:05 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:05.973+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.134:46522 @ 0x2400ab0" latency=683.398684ms timeout=20s Oct 16 16:14:05 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:05.973+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" Oct 16 16:14:05 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:05.974+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.134:46522 @ 0x2400ab0" latency=683.768948ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 16 16:14:05 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 16:14:05 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 16:14:06 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:06 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:06 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.034+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" name=volumio Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.051+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" language=en Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.059+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" timezone=Europe/Amsterdam Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.063+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" available=true connected=true macAddress=b8:27:eb:3e:48:44 ip4Address=192.168.1.11/24 ip6Address= Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.066+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.067+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" setupComplete=true Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:14:06 volumio volumio[671]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 16 16:14:06 volumio volumio[671]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 16 16:14:06 volumio volumio[671]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 16 16:14:06 volumio volumio[671]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 16 16:14:06 volumio volumio[671]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.508+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" selectedOutputId=1 Oct 16 16:14:06 volumio volumio[671]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.134 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:06 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:06 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:06 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:06 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:06 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.606+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" currentVersion=3.912 latestVersion=3.912 Oct 16 16:14:06 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:06.607+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" status=UPDATE_STATUS_NONE progress=0 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 16:14:06 volumio volumio[671]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.134 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:06 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:06 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:06 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:07 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:07 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:07 volumio volumio[671]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.134 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 16:14:07 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 16:14:08 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:08.450+02:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=cERP9eW90ch2Kr4pgbKOCy1HO933 tokenExpiry=2026-10-16T17:14:08.450+02:00 Oct 16 16:14:10 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:10.673+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" userId=cERP9eW90ch2Kr4pgbKOCy1HO933 Oct 16 16:14:10 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:10 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:10 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 16:14:10 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:10 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:10 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:10 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:11 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:11 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:12 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:12.196+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Oct 16 16:14:12 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:12.201+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Oct 16 16:14:12 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:12.201+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Oct 16 16:14:12 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:12.202+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Oct 16 16:14:12 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:12.202+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" providers=9 Oct 16 16:14:12 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:12 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:12 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.219+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" plugins=54 Oct 16 16:14:13 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:13 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.229+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" state=STATUS_STOPPED positionMs=416 volume=50 Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.231+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:46522 @ 0x2400ab0" id=mnt/USB/9e5f10f9-33b8-436c-a646-173099e024dc/sounds/tts/starboard_freshwater_tank_at_90_percent.mp3 title=starboard_freshwater_tank_at_90_percent.mp3 Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.267+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.976941ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.294+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.462+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=167.885622ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.506+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=http://pushupdates.volumio.org duration=204.976947ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.612+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=317.652378ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.724+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://functions.volumio.cloud duration=423.85225ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.724+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://functions.volumio.cloud duration=429.34604ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.743+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://securetoken.googleapis.com duration=447.250948ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.963+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://www.googleapis.com duration=669.15338ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.965+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=669.541192ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.981+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://google.com duration=686.321051ms Oct 16 16:14:13 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:13.988+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=693.653223ms Oct 16 16:14:14 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:14.346+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=http://plugins.volumio.org duration=1.048082551s Oct 16 16:14:14 volumio sudo[24507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 16:14:14 volumio sudo[24507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:14:14 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:14.371+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=https://database.volumio.cloud duration=1.070389117s Oct 16 16:14:14 volumio sudo[24507]: pam_unix(sudo:session): session closed for user root Oct 16 16:14:14 volumio sudo[24509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 16:14:14 volumio sudo[24509]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:14:14 volumio sudo[24509]: pam_unix(sudo:session): session closed for user root Oct 16 16:14:14 volumio volumio[671]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.134 UA: Mozilla/5.0 (Linux; Android 12; HD1903 Build/SKQ1.211113.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 16:14:14 volumio sudo[24515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 16:14:14 volumio sudo[24515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:14:14 volumio sudo[24515]: pam_unix(sudo:session): session closed for user root Oct 16 16:14:14 volumio sudo[24517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 16:14:14 volumio sudo[24517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:14:14 volumio sudo[24517]: pam_unix(sudo:session): session closed for user root Oct 16 16:14:15 volumio volumio[671]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.134 UA: Mozilla/5.0 (Linux; Android 12; HD1903 Build/SKQ1.211113.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:15 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::volumioGetQueue Oct 16 16:14:15 volumio volumio[671]: info: CoreStateMachine::getQueue Oct 16 16:14:15 volumio volumio[671]: info: CorePlayQueue::getQueue Oct 16 16:14:15 volumio volumio[671]: info: Listing playlists Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 16:14:15 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:15 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:15 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:15 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 16:14:15 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 16:14:15 volumio volumio5-onboarding[904]: time=2026-10-16T16:14:15.709+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.134:46522 @ 0x2400ab0" latency=666.93959ms timeout=10s endpoint=http://cddb.volumio.org duration=2.411958891s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:16 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:16 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:16 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:16 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:17 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 16:14:17 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:17 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:17 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:17 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:17 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:17 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:17 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:20 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:20 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:20 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:20 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:20 volumio volumio[671]: info: Listing playlists Oct 16 16:14:20 volumio volumio[671]: info: Listing playlists Oct 16 16:14:20 volumio volumio[671]: info: Listing playlists Oct 16 16:14:20 volumio volumio[671]: info: Listing playlists Oct 16 16:14:21 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:21 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:21 volumio volumio[671]: info: Listing playlists Oct 16 16:14:21 volumio volumio[671]: info: Listing playlists Oct 16 16:14:24 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 16:14:30 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:30 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:30 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:30 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:31 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:31 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 16:14:33 volumio volumio[671]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 16:14:33 volumio volumio[671]: info: Received Get System Version Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 16:14:33 volumio volumio[671]: info: Received Get System Info Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:14:33 volumio volumio[671]: info: Discovery: Getting this device information Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:33 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:33 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:14:40 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:40 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:40 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:40 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:40 volumio volumio[671]: info: Listing playlists Oct 16 16:14:40 volumio volumio[671]: info: Listing playlists Oct 16 16:14:40 volumio volumio[671]: info: Listing playlists Oct 16 16:14:40 volumio volumio[671]: info: Listing playlists Oct 16 16:14:41 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:41 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:41 volumio volumio[671]: info: Listing playlists Oct 16 16:14:41 volumio volumio[671]: info: Listing playlists Oct 16 16:14:49 volumio volumio[671]: Searching plugin music_service/mpd Oct 16 16:14:49 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 16:14:51 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:51 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:51 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:51 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:51 volumio volumio[671]: info: All search sources collected, pushing search results Oct 16 16:14:51 volumio volumio[671]: Searching plugin music_service/mpd Oct 16 16:14:51 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 16:14:51 volumio volumio[671]: info: CoreCommandRouter::volumioGetState Oct 16 16:14:51 volumio volumio[671]: info: CorePlayQueue::getTrack 0 Oct 16 16:14:52 volumio volumio[671]: info: All search sources collected, pushing search results Oct 16 16:14:53 volumio volumio[671]: Searching plugin music_service/mpd Oct 16 16:14:53 volumio volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 16:14:53 volumio volumio[671]: info: All search sources collected, pushing search results Oct 16 16:14:54 volumio volumio[671]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:14:54 volumio volumio[671]: TypeError: Cannot read property 'slice' of null Oct 16 16:14:54 volumio volumio[671]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 16:14:54 volumio volumio[671]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 16:14:54 volumio volumio[671]: at Object.onceWrapper (events.js:422:26) Oct 16 16:14:54 volumio volumio[671]: at ClientRequest.emit (events.js:315:20) Oct 16 16:14:54 volumio volumio[671]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Oct 16 16:14:54 volumio volumio[671]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Oct 16 16:14:54 volumio volumio[671]: at TLSSocket.socketOnData (_http_client.js:509:22) Oct 16 16:14:54 volumio volumio[671]: at TLSSocket.emit (events.js:315:20) Oct 16 16:14:54 volumio volumio[671]: at addChunk (internal/streams/readable.js:309:12) Oct 16 16:14:54 volumio volumio[671]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 16 16:14:54 volumio volumio[671]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Oct 16 16:14:54 volumio volumio[671]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 16 16:14:54 volumio volumio[671]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:14:55 volumio sudo[24592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 16:13 Oct 16 16:14:55 volumio sudo[24592]: 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"