-- Logs begin at Thu 2019-02-14 02:11:58 PST, end at Fri 2026-02-20 21:19:20 PST. -- Feb 20 21:18:00 living volumio-remote-updater[696]: No test mode Feb 20 21:18:00 living volumio-remote-updater[696]: No alpha test mode Feb 20 21:18:00 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 20 21:18:00 living volumio[1030]: info: Remote SSH Stopped Feb 20 21:18:00 living volumio[1030]: error: Cannot start Volumio Streaming Daemon Feb 20 21:18:00 living volumio[1030]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 20 21:18:00 living volumio[1030]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:03 living volumio[1030]: Cannot compose Albumart path Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::volumioGetBrowseSources Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 21:18:04 living volumio[1030]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 20 21:18:04 living volumio[1030]: info: Completed starting MyVolumio Plugin Feb 20 21:18:04 living volumio[1030]: [Metrics] CommandRouter: 40s 841.37ms Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::volumiosetStartupVolume Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 20 21:18:04 living volumio[1030]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 20 21:18:04 living volumio[1030]: info: Setting Geolocation for MyVolumio to us2 Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:04 living kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 20 21:18:04 living volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 20 21:18:04 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:04 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:04 living volumio[1030]: info: Successfully Added MyVolumio device Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 20 21:18:05 living volumio[1030]: info: Updating MyVolumio device info Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:05 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:05 living volumio[1030]: info: Successfully Updated MyVolumio device Feb 20 21:18:09 living ntpd[810]: Soliciting pool server 155.248.202.205 Feb 20 21:18:10 living ntpd[810]: Soliciting pool server 158.51.99.19 Feb 20 21:18:11 living volumio[1030]: info: BOOT COMPLETED Feb 20 21:18:11 living ntpd[810]: Soliciting pool server 50.251.160.20 Feb 20 21:18:12 living ntpd[810]: Soliciting pool server 45.63.54.13 Feb 20 21:18:12 living ntpd[810]: Soliciting pool server 163.123.152.14 Feb 20 21:18:13 living ntpd[810]: Soliciting pool server 172.233.177.198 Feb 20 21:18:13 living ntpd[810]: Soliciting pool server 45.45.184.13 Feb 20 21:18:13 living ntpd[810]: Soliciting pool server 144.202.62.209 Feb 20 21:18:14 living ntpd[810]: Soliciting pool server 216.229.0.49 Feb 20 21:18:14 living ntpd[810]: Soliciting pool server 23.157.160.168 Feb 20 21:18:14 living ntpd[810]: Soliciting pool server 162.159.200.123 Feb 20 21:18:14 living ntpd[810]: Soliciting pool server 170.187.147.56 Feb 20 21:18:14 living ntpd[810]: Soliciting pool server 142.202.190.19 Feb 20 21:18:15 living ntpd[810]: Soliciting pool server 50.205.57.38 Feb 20 21:18:15 living ntpd[810]: Soliciting pool server 108.61.73.244 Feb 20 21:18:15 living ntpd[810]: Soliciting pool server 50.205.57.38 Feb 20 21:18:15 living ntpd[810]: Soliciting pool server 23.150.40.242 Feb 20 21:18:16 living ntpd[810]: Soliciting pool server 23.186.168.123 Feb 20 21:18:16 living ntpd[810]: Soliciting pool server 45.33.53.84 Feb 20 21:18:16 living ntpd[810]: Soliciting pool server 23.150.41.122 Feb 20 21:18:19 living ntpd[810]: receive: Unexpected origin timestamp 0xed43be9a.a8097f90 does not match aorg 0000000000.00000000 from server@172.233.177.198 xmt 0xed43be9b.d355aef6 Feb 20 21:18:19 living ntpd[810]: receive: Unexpected origin timestamp 0xed43be9a.a8054d86 does not match aorg 0000000000.00000000 from server@50.205.57.38 xmt 0xed43be9b.d1b80fb5 Feb 20 21:18:19 living ntpd[810]: receive: Unexpected origin timestamp 0xed43be9a.a80a4610 does not match aorg 0000000000.00000000 from server@50.251.160.20 xmt 0xed43be9b.d090f9d4 Feb 20 21:18:34 living volumio5-onboarding[1311]: time=2026-02-20T21:18:34.660-08:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.86.84:58229 error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 20 21:18:34 living volumio5-onboarding[1311]: time=2026-02-20T21:18:34.660-08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.84:58229 Feb 20 21:18:34 living volumio5-onboarding[1311]: time=2026-02-20T21:18:34.660-08:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.84:58229 Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.833-08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.84:58231 Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.899-08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.86.84:58231 @ 0x1800d80" latency=94.855529ms platform=PLATFORM_IOS version=5.260120.0 Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.901-08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.84:58231 @ 0x1800d80" latency=96.937858ms timeout=10s Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.901-08:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 20 21:18:39 living volumio[1030]: info: Received Get System Info Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:18:39 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:39 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.908-08:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" name=Living Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.911-08:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" language=en Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.913-08:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" timezone=America/Los_Angeles Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.914-08:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" available=true connected=false macAddress= ip4Address= ip6Address= Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.919-08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" available=true connected=true macAddress=d8:3a:dd:2d:6e:0c ip4Address=192.168.86.98/24 ip6Address= ssid="Team MG" Feb 20 21:18:39 living volumio5-onboarding[1311]: time=2026-02-20T21:18:39.920-08:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" setupComplete=true Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:39 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 20 21:18:39 living volumio[1030]: amixer -c 2 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" Feb 20 21:18:40 living volumio[1030]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 20 21:18:40 living volumio[1030]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 20 21:18:40 living volumio[1030]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:18:40 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:40 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:18:40 living volumio[1030]: amixer -c 2 info | grep "snd_rpi_hifiberry_digi" Feb 20 21:18:40 living volumio[1030]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.178-08:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" selectedOutputId=2 Feb 20 21:18:40 living volumio[1030]: verbose: New Socket.io Connection to 192.168.86.98:3000 from 192.168.86.84 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 20 21:18:40 living volumio[1030]: info: Received Get System Info Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:18:40 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:40 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.203-08:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" currentVersion=3.908 latestVersion=3.908 Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.204-08:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.84:58231 @ 0x1800d80" status=UPDATE_STATUS_NONE progress=0 Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.204-08:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" userId= Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.205-08:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" providers=3 Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.899-08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.84:58231 @ 0x1800d80" latency=95.529038ms timeout=10s Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.899-08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.86.84:58231 @ 0x1800d80" latency=96.48215ms platform=PLATFORM_IOS version=5.260120.0 Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.899-08:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" Feb 20 21:18:40 living volumio[1030]: info: Received Get System Info Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:18:40 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:40 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.903-08:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" name=Living Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.904-08:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" language=en Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.906-08:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" timezone=America/Los_Angeles Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.907-08:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" available=true connected=false macAddress= ip4Address= ip6Address= Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.909-08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" available=true connected=true macAddress=d8:3a:dd:2d:6e:0c ip4Address=192.168.86.98/24 ip6Address= ssid="Team MG" Feb 20 21:18:40 living volumio5-onboarding[1311]: time=2026-02-20T21:18:40.909-08:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" setupComplete=true Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 21:18:40 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 20 21:18:40 living volumio[1030]: amixer -c 2 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" Feb 20 21:18:40 living volumio[1030]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 20 21:18:41 living volumio[1030]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 20 21:18:41 living volumio[1030]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 20 21:18:41 living volumio[1030]: amixer -c 2 info | grep "snd_rpi_hifiberry_digi" Feb 20 21:18:41 living volumio[1030]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.145-08:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" selectedOutputId=2 Feb 20 21:18:41 living volumio[1030]: info: Received Get System Info Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:18:41 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:41 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.214-08:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" currentVersion=3.908 latestVersion=3.908 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.214-08:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.84:58231 @ 0x1800d80" status=UPDATE_STATUS_NONE progress=0 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.214-08:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" userId= Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.215-08:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" providers=3 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.224-08:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" plugins=54 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.225-08:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" plugins=54 Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:41 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.230-08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" state=STATUS_STOPPED positionMs=0 volume=75 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.231-08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" id="mnt/USB/FLACs/FLACs/Soundtrack/VA - Trainspotting/01 Iggy Pop - Lust for Life.flac" title="Lust for Life" Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.231-08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" state=STATUS_STOPPED positionMs=0 volume=75 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.231-08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" id="mnt/USB/FLACs/FLACs/Soundtrack/VA - Trainspotting/01 Iggy Pop - Lust for Life.flac" title="Lust for Life" Feb 20 21:18:41 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:41 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.234-08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" state=STATUS_STOPPED positionMs=0 volume=75 Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.235-08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.84:58231 @ 0x1800d80" id="mnt/USB/FLACs/FLACs/Soundtrack/VA - Trainspotting/01 Iggy Pop - Lust for Life.flac" title="Lust for Life" Feb 20 21:18:41 living volumio5-onboarding[1311]: time=2026-02-20T21:18:41.303-08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.86.84:58231 @ 0x1800d80" latency=95.035587ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 20 21:18:42 living sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 20 21:18:42 living sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 21:18:42 living sudo[1634]: pam_unix(sudo:session): session closed for user root Feb 20 21:18:42 living sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 20 21:18:42 living sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 21:18:42 living sudo[1637]: pam_unix(sudo:session): session closed for user root Feb 20 21:18:42 living volumio[1030]: verbose: New Socket.io Connection to 192.168.86.98 from 192.168.86.84 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 20 21:18:42 living sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 20 21:18:42 living sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 21:18:42 living sudo[1640]: pam_unix(sudo:session): session closed for user root Feb 20 21:18:42 living sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 20 21:18:42 living sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 21:18:42 living sudo[1643]: pam_unix(sudo:session): session closed for user root Feb 20 21:18:42 living volumio[1030]: verbose: New Socket.io Connection to 192.168.86.98 from 192.168.86.84 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:18:42 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 20 21:18:42 living volumio[1030]: info: Listing playlists Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 20 21:18:42 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 20 21:18:46 living volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Feb 20 21:18:46 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:18:46 living volumio[1030]: info: CoreCommandRouter::volumioGetQueue Feb 20 21:18:46 living volumio[1030]: info: CoreStateMachine::getQueue Feb 20 21:18:46 living volumio[1030]: info: CorePlayQueue::getQueue Feb 20 21:18:52 living volumio[1030]: Searching all installed plugins Feb 20 21:18:52 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:18:52 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:18:52 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:18:52 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:18:52 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:18:53 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:18:58 living volumio[1030]: Searching all installed plugins Feb 20 21:18:58 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:18:58 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:18:58 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:18:58 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:18:58 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:18:59 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:19:00 living volumio[1030]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 20 21:19:00 living volumio[1030]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 20 21:19:00 living volumio[1030]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 20 21:19:00 living volumio[1030]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 20 21:19:00 living volumio[1030]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 20 21:19:00 living volumio[1030]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 20 21:19:00 living volumio[1030]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 20 21:19:00 living volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 21:19:01 living volumio[1030]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 20 21:19:01 living volumio[1030]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 20 21:19:01 living volumio[1030]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 20 21:19:01 living volumio[1030]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 20 21:19:01 living volumio[1030]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 20 21:19:01 living volumio[1030]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 20 21:19:01 living volumio[1030]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 20 21:19:01 living volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 21:19:01 living volumio[1030]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 20 21:19:01 living volumio[1030]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 20 21:19:01 living volumio[1030]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 20 21:19:01 living volumio[1030]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 20 21:19:01 living volumio[1030]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 20 21:19:01 living volumio[1030]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 20 21:19:01 living volumio[1030]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 20 21:19:01 living volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 21:19:01 living volumio[1030]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 20 21:19:01 living volumio[1030]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 20 21:19:01 living volumio[1030]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 20 21:19:01 living volumio[1030]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 20 21:19:01 living volumio[1030]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 20 21:19:01 living volumio[1030]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 20 21:19:01 living volumio[1030]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 20 21:19:01 living volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 20 21:19:01 living volumio[1030]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 20 21:19:01 living volumio[1030]: info: Received Get System Version Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 21:19:01 living volumio[1030]: info: Received Get System Info Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 21:19:01 living volumio[1030]: info: Discovery: Getting this device information Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 20 21:19:01 living volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 20 21:19:01 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 21:19:03 living volumio[1030]: Searching all installed plugins Feb 20 21:19:03 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:19:03 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:19:03 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:19:03 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:19:03 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:19:03 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:19:12 living volumio[1030]: Searching all installed plugins Feb 20 21:19:12 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:19:12 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:19:12 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:19:12 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:19:12 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: Cannot compose Albumart path Feb 20 21:19:13 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:19:14 living volumio[1030]: Searching all installed plugins Feb 20 21:19:14 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:19:14 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:19:14 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:19:14 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:19:14 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:19:15 living volumio[1030]: Searching all installed plugins Feb 20 21:19:15 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 21:19:15 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Feb 20 21:19:15 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 20 21:19:15 living volumio[1030]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Feb 20 21:19:15 living volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Feb 20 21:19:15 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:19:16 living volumio[1030]: info: All search sources collected, pushing search results Feb 20 21:19:18 living volumio[1030]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Feb 20 21:19:19 living volumio[1030]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 20 21:19:19 living volumio[1030]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 20 21:19:19 living volumio[1030]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 20 21:19:19 living volumio[1030]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 20 21:19:19 living volumio[1030]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 20 21:19:19 living volumio[1030]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 20 21:19:19 living volumio[1030]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 20 21:19:19 living volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 21:19:20 living volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 21:19:20 living volumio[1030]: TypeError: Cannot read property 'slice' of null Feb 20 21:19:20 living volumio[1030]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 20 21:19:20 living volumio[1030]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 20 21:19:20 living volumio[1030]: at Object.onceWrapper (events.js:422:26) Feb 20 21:19:20 living volumio[1030]: at ClientRequest.emit (events.js:315:20) Feb 20 21:19:20 living volumio[1030]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Feb 20 21:19:20 living volumio[1030]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Feb 20 21:19:20 living volumio[1030]: at TLSSocket.socketOnData (_http_client.js:509:22) Feb 20 21:19:20 living volumio[1030]: at TLSSocket.emit (events.js:315:20) Feb 20 21:19:20 living volumio[1030]: at addChunk (internal/streams/readable.js:309:12) Feb 20 21:19:20 living volumio[1030]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 20 21:19:20 living volumio[1030]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Feb 20 21:19:20 living volumio[1030]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 20 21:19:20 living volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 21:19:20 living sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-20 21:18 Feb 20 21:19:20 living sudo[1748]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"