-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Wed 2026-02-11 17:39:29 GMT. -- Feb 11 17:38:00 volumio ntpd[749]: receive: Unexpected origin timestamp 0xed373cf7.040f2289 does not match aorg 0000000000.00000000 from server@131.111.8.60 xmt 0xed373cf8.80a4887e Feb 11 17:38:00 volumio ntpd[749]: receive: Unexpected origin timestamp 0xed373cf7.041fca73 does not match aorg 0000000000.00000000 from server@77.104.162.218 xmt 0xed373cf8.82012274 Feb 11 17:38:00 volumio ntpd[749]: receive: Unexpected origin timestamp 0xed373cf7.0417bb26 does not match aorg 0000000000.00000000 from server@91.109.118.94 xmt 0xed373cf8.81ed75e9 Feb 11 17:38:00 volumio ntpd[749]: receive: Unexpected origin timestamp 0xed373cf7.041af8f3 does not match aorg 0000000000.00000000 from server@87.106.36.214 xmt 0xed373cf8.858236a5 Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 17:38:01 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:38:02 volumio volumio[970]: info: Enabling plugin touch_display Feb 11 17:38:02 volumio volumio[970]: info: Loading plugin "touch_display"... Feb 11 17:38:02 volumio volumio[970]: info: Applying required configuration parameters for plugin touch_display Feb 11 17:38:02 volumio volumio[970]: info: PLUGIN START: touch_display Feb 11 17:38:02 volumio volumio[970]: info: Loading i18n strings for locale en Feb 11 17:38:02 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:38:02 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 17:38:02 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:02 volumio sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 17:38:02 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:02 volumio volumio[970]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Feb 11 17:38:02 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 17:38:02 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:02 volumio volumio[970]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 17:38:02 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 17:38:02 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:02 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:02 volumio volumio[970]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/99-vc4.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 17:38:02 volumio volumio[970]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/99-vc4.conf': No such file or directory Feb 11 17:38:02 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:02 volumio systemd[1]: Reloading. Feb 11 17:38:02 volumio volumio[970]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 17:38:03 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 17:38:03 volumio volumio[970]: warn: [now-playing] MyBackgroundMonitor stopped Feb 11 17:38:03 volumio volumio[970]: info: Disabling plugin now_playing Feb 11 17:38:03 volumio volumio[970]: info: Done. Feb 11 17:38:03 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:03 volumio volumio[970]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 17:38:03 volumio systemd[1]: Reloading. Feb 11 17:38:04 volumio volumio[970]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 11 17:38:04 volumio volumio[970]: info: Completed starting MyVolumio Plugin Feb 11 17:38:04 volumio volumio[970]: [Metrics] CommandRouter: 57s 781.50ms Feb 11 17:38:04 volumio volumio[970]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 17:38:04 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:38:04 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:38:04 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Feb 11 17:38:04 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Feb 11 17:38:04 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 17:38:05 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 17:38:05 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 17:38:05 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:05 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 17:38:05 volumio volumio[970]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 17:38:05 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 17:38:05 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:05 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:05 volumio volumio[970]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 11 17:38:05 volumio volumio[970]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 11 17:38:05 volumio volumio[970]: info: Error: Error Feb 11 17:38:07 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 17:38:07 volumio volumio[970]: info: Enabling plugin now_playing Feb 11 17:38:07 volumio volumio[970]: info: Loading plugin "now_playing"... Feb 11 17:38:07 volumio volumio[970]: info: PLUGIN START: now_playing Feb 11 17:38:07 volumio volumio[970]: info: [now-playing] ConfigUpdater: config is up to date. Feb 11 17:38:07 volumio volumio[970]: info: [now-playing] App is listening on port 4004. Feb 11 17:38:07 volumio volumio[970]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 11 17:38:07 volumio volumio[970]: info: Done. Feb 11 17:38:09 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:38:11 volumio volumio[970]: info: BOOT COMPLETED Feb 11 17:38:11 volumio volumio[970]: warn: [now-playing] MyBackgroundMonitor stopped Feb 11 17:38:11 volumio volumio[970]: info: Disabling plugin now_playing Feb 11 17:38:11 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:38:11 volumio volumio[970]: info: Done. Feb 11 17:38:13 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 17:38:13 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:13 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:13 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 11 17:38:13 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:13 volumio volumio[970]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 17:38:13 volumio volumio[970]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/95-touch_display-plugin.conf': No such file or directory Feb 11 17:38:13 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 11 17:38:13 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:13 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 11 17:38:13 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:13 volumio systemd[1]: Created slice system-getty.slice. Feb 11 17:38:13 volumio systemd[1]: Started Getty on tty1. Feb 11 17:38:13 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:13 volumio volumio[970]: info: touch_display: systemctl start getty@tty1.service succeeded. Feb 11 17:38:13 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:13 volumio volumio[970]: error: touch_display: Failed to stop volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl stop volumio-kiosk.service Feb 11 17:38:13 volumio volumio[970]: Failed to stop volumio-kiosk.service: Unit volumio-kiosk.service not loaded. Feb 11 17:38:13 volumio systemd[1]: Reloading. Feb 11 17:38:13 volumio volumio[970]: info: Disabling plugin touch_display Feb 11 17:38:13 volumio volumio[970]: info: Done. Feb 11 17:38:14 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 17:38:14 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 11 17:38:15 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:15 volumio volumio[970]: info: touch_display: systemctl enable getty@tty1.service succeeded. Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 17:38:15 volumio volumio[970]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 17:38:15 volumio volumio[970]: info: Received Get System Version Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:38:15 volumio volumio[970]: info: Received Get System Info Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:38:15 volumio volumio[970]: info: Discovery: Getting this device information Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Feb 11 17:38:15 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Feb 11 17:38:15 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:38:19 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:38:20 volumio volumio[970]: info: Enabling plugin touch_display Feb 11 17:38:20 volumio volumio[970]: info: Loading plugin "touch_display"... Feb 11 17:38:20 volumio volumio[970]: info: Applying required configuration parameters for plugin touch_display Feb 11 17:38:20 volumio volumio[970]: info: PLUGIN START: touch_display Feb 11 17:38:20 volumio volumio[970]: info: Loading i18n strings for locale en Feb 11 17:38:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:38:20 volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 17:38:20 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:20 volumio sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 17:38:20 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:20 volumio systemd[1]: Stopping Getty on tty1... Feb 11 17:38:20 volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 17:38:20 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:20 volumio volumio[970]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Feb 11 17:38:20 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Feb 11 17:38:20 volumio systemd[1]: getty@tty1.service: Succeeded. Feb 11 17:38:20 volumio systemd[1]: Stopped Getty on tty1. Feb 11 17:38:20 volumio volumio[970]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 17:38:20 volumio systemd[1]: Reloading. Feb 11 17:38:20 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 17:38:20 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:20 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:20 volumio volumio[970]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/99-vc4.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 17:38:20 volumio volumio[970]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/99-vc4.conf': No such file or directory Feb 11 17:38:20 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 17:38:20 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:20 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:20 volumio volumio[970]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 17:38:20 volumio volumio[970]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/95-touch_display-plugin.conf': No such file or directory Feb 11 17:38:20 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 17:38:21 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:21 volumio volumio[970]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 17:38:21 volumio systemd[1]: Reloading. Feb 11 17:38:21 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:21 volumio volumio[970]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 17:38:21 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 17:38:21 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:38:22 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:38:22 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 17:38:23 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:23 volumio volumio[970]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 17:38:23 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Feb 11 17:38:23 volumio volumio[970]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 11 17:38:23 volumio volumio[970]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 11 17:38:23 volumio volumio[970]: info: Error: Error Feb 11 17:38:26 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:38:26 volumio volumio[970]: info: Enabling plugin now_playing Feb 11 17:38:26 volumio volumio[970]: info: Loading plugin "now_playing"... Feb 11 17:38:26 volumio volumio[970]: info: PLUGIN START: now_playing Feb 11 17:38:26 volumio volumio[970]: info: [now-playing] ConfigUpdater: config is up to date. Feb 11 17:38:26 volumio volumio[970]: info: [now-playing] App is listening on port 4004. Feb 11 17:38:26 volumio volumio[970]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 11 17:38:26 volumio volumio[970]: info: Done. Feb 11 17:38:28 volumio volumio[970]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 17:38:28 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:38:28 volumio volumio[970]: info: Discovery: Getting this device information Feb 11 17:38:28 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Feb 11 17:38:28 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Feb 11 17:38:28 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:38:34 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:38:36 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Feb 11 17:38:36 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Feb 11 17:38:38 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 17:38:38 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 17:38:42 volumio volumio[970]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 17:38:49 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:38:50 volumio volumio[970]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Feb 11 17:38:50 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Feb 11 17:38:50 volumio volumio[970]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :undefined s reset dpms force on Feb 11 17:38:50 volumio volumio[970]: /usr/bin/xset: unable to open display ":undefined" Feb 11 17:38:50 volumio volumio[970]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/Xundefined' Feb 11 17:38:52 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 17:38:52 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 17:38:53 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:38:59 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:39:00 volumio volumio[970]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Feb 11 17:39:00 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:00.346Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:03 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:39:04 volumio volumio[970]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Feb 11 17:39:04 volumio volumio[970]: warn: [now-playing] MyBackgroundMonitor stopped Feb 11 17:39:04 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:04.250Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:04 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:04.264Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:05 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:05.979Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:05 volumio volumio[970]: info: Check plugin dependencies Feb 11 17:39:06 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:39:06 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:06.072Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:06 volumio volumio[970]: info: Rename folder Feb 11 17:39:06 volumio volumio[970]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 17:39:06 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:06.117Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 17:39:06 volumio volumio[970]: info: Move to category Feb 11 17:39:20 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 17:39:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.493Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" latency=-154.376846ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.497Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" latency=-152.182211ms timeout=10s Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.497Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 17:39:27 volumio volumio[970]: info: Received Get System Info Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:39:27 volumio volumio[970]: info: Discovery: Getting this device information Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Feb 11 17:39:27 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.505Z level=INFO msg="emitting device name changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" name=Volumio Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.510Z level=INFO msg="emitting device language changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" language=en Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.514Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" timezone=Europe/London Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.516Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" available=true connected=false macAddress= ip4Address= ip6Address= Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.518Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" available=true connected=true macAddress=e8:4e:06:1b:78:2b ip4Address=192.168.5.189/24 ip6Address= ssid=vm Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.519Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" setupComplete=true Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 17:39:27 volumio volumio[970]: amixer -c 0 info | grep "rockchip" Feb 11 17:39:27 volumio volumio[970]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 11 17:39:27 volumio volumio[970]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 11 17:39:27 volumio volumio[970]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.904Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" selectedOutputId=0 Feb 11 17:39:27 volumio volumio[970]: info: Received Get System Info Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:39:27 volumio volumio[970]: info: Discovery: Getting this device information Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Feb 11 17:39:27 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.946Z level=INFO msg="emitting software info changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" currentVersion=3.908 latestVersion=3.908 Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.948Z level=INFO msg="emitting software update progress event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" status=UPDATE_STATUS_NONE progress=0 Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.949Z level=INFO msg="emitting user changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" userId= Feb 11 17:39:27 volumio volumio5-onboarding[1158]: time=2026-02-11T17:39:27.950Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.5.46:36312,192.168.5.46:38592 @ 0x28c7350" providers=3 Feb 11 17:39:27 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 17:39:28 volumio volumio[970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:39:28 volumio volumio[970]: TypeError: Cannot read property 'plugin_type' of undefined Feb 11 17:39:28 volumio volumio[970]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Feb 11 17:39:28 volumio volumio[970]: at PluginManager.getAvailablePlugins (/volumio/app/pluginmanager.js:1691:24) Feb 11 17:39:28 volumio volumio[970]: at CoreCommandRouter.getAvailablePlugins (/volumio/app/index.js:1544:29) Feb 11 17:39:28 volumio volumio[970]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1365:45) Feb 11 17:39:28 volumio volumio[970]: at Socket.emit (events.js:315:20) Feb 11 17:39:28 volumio volumio[970]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 11 17:39:28 volumio volumio[970]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 11 17:39:28 volumio volumio[970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:39:29 volumio sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 17:38 Feb 11 17:39:29 volumio sudo[1833]: 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"