Mar 26 11:24:00 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:00 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:00 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:00 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:10 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:10 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:19 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:24:19-04:00" level=trace msg="sent dealer ping" Mar 26 11:24:19 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:24:19-04:00" level=trace msg="received dealer pong" Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.398-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.4:37524 Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.448-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.4:37524 @ 0x2c002d0" latency=-487.041073ms timeout=10s Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.448-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.448-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.4:37524 @ 0x2c002d0" latency=-486.712132ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.450-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" name=GBVPi-Bedroom Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.451-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" language=en Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.453-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" timezone=America/New_York Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.454-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.458-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" available=true connected=true macAddress=dc:a6:32:78:9d:47 ip4Address=192.168.1.97/24 ip6Address= ssid=FCCViolation Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.458-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" setupComplete=true Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.576-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" selectedOutputId=1 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.599-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" currentVersion=4.119 latestVersion=4.119 Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.599-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" status=UPDATE_STATUS_NONE progress=0 Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.600-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" userId= Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.600-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" providers=3 Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.600-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" plugins=62 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.602-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" state=STATUS_STOPPED positionMs=251 volume=60 Mar 26 11:24:19 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:24:19.602-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" id=http://wfmu.org/wfmu.pls title=WFMU Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: verbose: New Socket.io Connection to 192.168.1.97:3000 from 192.168.1.4 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 11:24:19 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 11:24:20 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:20 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:20 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:20 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:20 gbvpi-bedroom bluealsa[953]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_74_E3_40_30_E7_AE, ...) Mar 26 11:24:28 gbvpi-bedroom bluealsa[953]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_67_0C_D6_60_D9_E1, ...) Mar 26 11:24:30 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:30 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:40 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:40 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:40 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:40 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:24:49 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:24:49-04:00" level=trace msg="sent dealer ping" Mar 26 11:24:49 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:24:49-04:00" level=trace msg="received dealer pong" Mar 26 11:24:50 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:24:50 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:24:52 gbvpi-bedroom bluealsa[953]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_74_E3_40_30_E7_AE, ...) Mar 26 11:25:00 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:00 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:00 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:00 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:10 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:10 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:19 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:25:19-04:00" level=trace msg="sent dealer ping" Mar 26 11:25:19 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:25:19-04:00" level=trace msg="received dealer pong" Mar 26 11:25:20 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:20 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:20 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:20 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:22 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:25:22-04:00" level=trace msg="received accesspoint ping" Mar 26 11:25:22 gbvpi-bedroom go-librespot[1597]: time="2026-03-26T11:25:22-04:00" level=trace msg="received accesspoint pong ack" Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.021-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.4:37524 @ 0x2c002d0" latency=-481.922732ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.021-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.4:37524 @ 0x2c002d0" latency=-481.701382ms timeout=10s Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.021-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.023-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" name=GBVPi-Bedroom Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.024-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" language=en Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.025-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" timezone=America/New_York Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.026-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.029-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" available=true connected=true macAddress=dc:a6:32:78:9d:47 ip4Address=192.168.1.97/24 ip6Address= ssid=FCCViolation Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.029-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" setupComplete=true Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.138-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" selectedOutputId=1 Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.161-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" currentVersion=4.119 latestVersion=4.119 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.161-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" status=UPDATE_STATUS_NONE progress=0 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.161-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" userId= Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.162-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" providers=3 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.163-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" plugins=62 Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.166-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" state=STATUS_STOPPED positionMs=251 volume=60 Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.167-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.4:37524 @ 0x2c002d0" id=http://wfmu.org/wfmu.pls title=WFMU Mar 26 11:25:26 gbvpi-bedroom volumio5-onboarding[1521]: time=2026-03-26T11:25:26.206-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.4:37524 @ 0x2c002d0" latency=-487.397653ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 11:25:26 gbvpi-bedroom sudo[16607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 11:25:26 gbvpi-bedroom sudo[16607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 11:25:26 gbvpi-bedroom sudo[16607]: pam_unix(sudo:session): session closed for user root Mar 26 11:25:26 gbvpi-bedroom sudo[16609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 11:25:26 gbvpi-bedroom sudo[16609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 11:25:26 gbvpi-bedroom sudo[16609]: pam_unix(sudo:session): session closed for user root Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 11:25:26 gbvpi-bedroom sudo[16613]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 11:25:26 gbvpi-bedroom sudo[16613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 11:25:26 gbvpi-bedroom sudo[16613]: pam_unix(sudo:session): session closed for user root Mar 26 11:25:26 gbvpi-bedroom sudo[16615]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 11:25:26 gbvpi-bedroom sudo[16615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 11:25:26 gbvpi-bedroom sudo[16615]: pam_unix(sudo:session): session closed for user root Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 16; SM-F766U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 26 11:25:26 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 11:25:27 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:28 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreStateMachine::getQueue Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getQueue Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: Received Get System Info Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: Discovery: Getting this device information Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:29 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 11:25:30 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:30 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:30 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 26 11:25:30 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] browseUri: jellyfin Mar 26 11:25:30 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] browseUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9 Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: [jellyfin-conn] Using previously assigned device Id for music@jellyfin-neo: 777d86fe-f9a7-4a6b-b5d3-645b1c861cf5 Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: [jellyfin-conn] Login successful: music@jellyfin-neo Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:31 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] browseUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=43cd7e46dedbbe1288b93b2e843d90c0 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=e2eb5a6919738ef61a97e19f5d451bf4 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=41c20af0f370a734946843d75b5db5d1 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=e3939ab87aa8c9740e3f60dc6311d840 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=05faa741d42191314896c364a08d5f66 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=07c8f49d38fa91ea66a35beff0054bdf Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=de82fd4c661e499939e075374d38ecea Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=4e7f965e8e2591609bb39354d2586b4a Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=7bd112c49428cde4d5f1a8d4f3b5f472 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Preloading song: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=15c5a1105d0889ed9459bafa90640144 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Exploding uri jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=07c8f49d38fa91ea66a35beff0054bdf in service jellyfin Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] explodeUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=07c8f49d38fa91ea66a35beff0054bdf Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Exploding uri jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=de82fd4c661e499939e075374d38ecea in service jellyfin Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] explodeUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=de82fd4c661e499939e075374d38ecea Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Exploding uri jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=4e7f965e8e2591609bb39354d2586b4a in service jellyfin Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] explodeUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=4e7f965e8e2591609bb39354d2586b4a Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Exploding uri jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=7bd112c49428cde4d5f1a8d4f3b5f472 in service jellyfin Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] explodeUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=7bd112c49428cde4d5f1a8d4f3b5f472 Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: Exploding uri jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=15c5a1105d0889ed9459bafa90640144 in service jellyfin Mar 26 11:25:33 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] explodeUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=15c5a1105d0889ed9459bafa90640144 Mar 26 11:25:35 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 26 11:25:35 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] browseUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/artists@parentId=7e64e319657a9516ec78490da03edccb Mar 26 11:25:35 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:35 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:36 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 11:25:40 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 26 11:25:40 gbvpi-bedroom volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 26 11:25:40 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:40 gbvpi-bedroom volumio[1224]: info: Listing playlists Mar 26 11:25:41 gbvpi-bedroom volumio[1224]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 26 11:25:41 gbvpi-bedroom volumio[1224]: info: [jellyfin-browse] browseUri: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/library@parentId=7e64e319657a9516ec78490da03edccb/artists@parentId=7e64e319657a9516ec78490da03edccb@startIndex=47@noExplode=1 Mar 26 11:25:41 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:41 gbvpi-bedroom volumio[1224]: info: Preload queue cleared Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: TypeError: Cannot read properties of null (reading 'slice') Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at Object.onceWrapper (node:events:629:26) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at ClientRequest.emit (node:events:514:28) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at TLSSocket.emit (node:events:514:28) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at addChunk (node:internal/streams/readable:343:12) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at Readable.push (node:internal/streams/readable:253:10) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 26 11:25:43 gbvpi-bedroom volumio[1224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 11:25:44 gbvpi-bedroom sudo[16667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 11:24' Mar 26 11:25:44 gbvpi-bedroom sudo[16667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"