Jul 06 09:56:00 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 06 09:56:00 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.50' (uid=0 pid=3013 comm="timedatectl show --property=NTPSynchronized --valu") Jul 06 09:56:00 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 06 09:56:00 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 06 09:56:00 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 06 09:56:00 volumio setdatetime-helper.sh[3012]: Time is not synchronized. Attempting to sync... Jul 06 09:56:00 volumio sudo[3021]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 06 Jul 2025 01:56:00 GMT#015' Jul 06 09:56:00 volumio sudo[3021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 06 09:56:00 volumio setdatetime-helper.sh[3022]: Sun Jul 6 09:56:00 CST 2025 Jul 06 09:56:00 volumio sudo[3021]: pam_unix(sudo:session): session closed for user root Jul 06 09:56:00 volumio setdatetime-helper.sh[3012]: Time synchronized successfully. Jul 06 09:56:00 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 06 09:56:00 volumio systemd-journald[360]: Time jumped backwards, rotating. Jul 06 09:56:00 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 06 09:56:09 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Jul 06 09:56:09 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jul 06 09:56:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 09:56:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 06 09:56:23 volumio volumio[1221]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Jul 06 09:56:28 volumio volumio[1221]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Jul 06 09:56:28 volumio sudo[3071]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Jul 06 09:56:28 volumio sudo[3071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 09:56:28 volumio sudo[3071]: pam_unix(sudo:session): session closed for user root Jul 06 09:56:28 volumio volumio[1221]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jul 06 09:56:28 volumio sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jul 06 09:56:28 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 09:56:28 volumio sudo[3074]: pam_unix(sudo:session): session closed for user root Jul 06 09:56:28 volumio volumio[1221]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Jul 06 09:56:28 volumio sudo[3077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Jul 06 09:56:28 volumio sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 09:56:28 volumio startx[2749]: xinit: connection to X server lost Jul 06 09:56:28 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Jul 06 09:56:28 volumio startx[2749]: Jul 06 09:56:28 volumio startx[2749]: waiting for X server to shut down Jul 06 09:56:28 volumio startx[2750]: (II) Server terminated successfully (0). Closing log file. Jul 06 09:56:29 volumio startx[2749]: xinit: unexpected signal 15 Jul 06 09:56:29 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jul 06 09:56:29 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jul 06 09:56:29 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Jul 06 09:56:29 volumio systemd[1]: volumio-kiosk.service: Consumed 1min 24.225s CPU time. Jul 06 09:56:29 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jul 06 09:56:29 volumio sudo[3077]: pam_unix(sudo:session): session closed for user root Jul 06 09:56:29 volumio volumio[1221]: warn: [now-playing] MyBackgroundMonitor stopped Jul 06 09:56:29 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 09:56:29 volumio startx[3108]: X.Org X Server 1.21.1.7 Jul 06 09:56:29 volumio startx[3108]: X Protocol Version 11, Revision 0 Jul 06 09:56:29 volumio startx[3108]: Current Operating System: Linux volumio 6.12.27-v7l+ #1876 SMP Tue May 6 15:10:05 BST 2025 armv7l Jul 06 09:56:29 volumio startx[3108]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:480x1920M@60D numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=D8:3A:DD:5D:89:A0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=3791128c-179c-4361-8fb4-cca9c81d917c imgfile=/volumio_current.sqsh bootpart=UUID=956C-EE98 datapart=UUID=fae27dad-e20e-4ddf-8191-8c7f2191314f uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 06 09:56:29 volumio startx[3108]: xorg-server 2:21.1.7-3+rpt3+deb12u10 (https://www.debian.org/support) Jul 06 09:56:29 volumio startx[3108]: Current version of pixman: 0.44.0 Jul 06 09:56:29 volumio startx[3108]: Before reporting problems, check http://wiki.x.org Jul 06 09:56:29 volumio startx[3108]: to make sure that you have the latest version. Jul 06 09:56:29 volumio startx[3108]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 06 09:56:29 volumio startx[3108]: (++) from command line, (!!) notice, (II) informational, Jul 06 09:56:29 volumio startx[3108]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 06 09:56:29 volumio startx[3108]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jul 6 09:56:29 2025 Jul 06 09:56:29 volumio startx[3108]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 06 09:56:29 volumio startx[3108]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 06 09:56:34 volumio volumio[1221]: info: Check plugin dependencies Jul 06 09:56:34 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 09:56:34 volumio volumio[1221]: info: Rename folder Jul 06 09:56:34 volumio volumio[1221]: info: Folder /tmp/downloaded_plugin.zip removed Jul 06 09:56:34 volumio volumio[1221]: info: Move to category Jul 06 09:56:35 volumio volumio[1221]: error: Error moving plugin folder: Error: Command failed: /bin/mv /data/plugins/now_playing /data/plugins/user_interface Jul 06 09:56:35 volumio volumio[1221]: /bin/mv: cannot move '/data/plugins/now_playing' to '/data/plugins/user_interface/now_playing': Directory not empty Jul 06 09:56:35 volumio volumio[1221]: info: An error occurred installing the plugin. Rolling back config Jul 06 09:56:35 volumio volumio[1221]: info: Plugin folders cleanup Jul 06 09:56:35 volumio volumio[1221]: info: Scanning into folder /volumio/app/plugins/ Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category audio_interface Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category miscellanea Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category music_service Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category plugins.json Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category system_controller Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category user_interface Jul 06 09:56:35 volumio volumio[1221]: info: Scanning into folder /data/plugins/ Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category music_service Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category now_playing Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/README.md Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/config.json Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/eslint.config.mjs Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/fixup.sh Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/gitignore Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/install.sh Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/package-lock.json Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/package.json Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/tsconfig.json Jul 06 09:56:35 volumio volumio[1221]: info: Removing /data/plugins//now_playing/uninstall.sh Jul 06 09:56:35 volumio volumio[1221]: info: Scanning category user_interface Jul 06 09:56:35 volumio volumio[1221]: info: Plugin folders cleanup completed Jul 06 09:56:35 volumio volumio[1221]: info: Error: Error Jul 06 09:56:35 volumio volumio[1221]: info: Error: Error Jul 06 09:56:36 volumio volumio[1221]: info: Folder /tmp/plugins removed Jul 06 09:56:36 volumio volumio[1221]: info: Folder /tmp/downloaded_plugin.zip removed Jul 06 09:56:38 volumio volumio[1221]: info: Folder /data/temp removed Jul 06 09:57:14 volumio sudo[3373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 09:57:14 volumio sudo[3373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 09:57:14 volumio sudo[3373]: pam_unix(sudo:session): session closed for user root Jul 06 09:57:14 volumio sudo[3375]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 09:57:14 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 09:57:14 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Jul 06 09:57:14 volumio volumio[1221]: verbose: New Socket.io Connection to 192.168.0.44 from 192.168.0.66 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 06 09:57:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 09:57:14 volumio volumio[1221]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 09:57:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 09:57:14 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 09:57:14 volumio volumio[1221]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Jul 06 09:57:14 volumio volumio[1221]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jul 06 09:57:14 volumio volumio[1221]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Jul 06 09:57:14 volumio volumio[1221]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) Jul 06 09:57:14 volumio volumio[1221]: at Socket.emit (node:events:514:28) Jul 06 09:57:14 volumio volumio[1221]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 06 09:57:14 volumio volumio[1221]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jul 06 09:57:14 volumio volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 09:57:15 volumio sudo[3392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-06 09:56' Jul 06 09:57:15 volumio sudo[3392]: 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"