Jun 08 22:49:17 volupi52 ntpd[1135]: PROTO: 194.58.203.148 unlink local addr 192.168.50.209 -> Jun 08 22:49:26 volupi52 ntpd[1135]: PROTO: 192.36.143.130 unlink local addr 192.168.50.209 -> Jun 08 22:49:38 volupi52 ntpd[1135]: PROTO: 194.58.202.20 unlink local addr 192.168.50.209 -> Jun 08 22:49:40 volupi52 ntpd[1135]: PROTO: 192.121.108.99 unlink local addr 192.168.50.209 -> Jun 08 22:49:40 volupi52 ntpd[1135]: PROTO: 162.159.200.1 unlink local addr 192.168.50.209 -> Jun 08 22:49:40 volupi52 ntpd[1135]: PROTO: 194.58.200.20 unlink local addr 192.168.50.209 -> Jun 08 22:49:42 volupi52 ntpd[1135]: PROTO: 193.182.111.13 unlink local addr 192.168.50.209 -> Jun 08 22:49:43 volupi52 ntpd[1135]: PROTO: 192.121.108.100 unlink local addr 192.168.50.209 -> Jun 08 22:49:44 volupi52 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 08 22:49:44 volupi52 dbus-daemon[939]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.682' (uid=0 pid=26496 comm="timedatectl show --property=NTPSynchronized --valu") Jun 08 22:49:44 volupi52 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 08 22:49:44 volupi52 dbus-daemon[939]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 08 22:49:44 volupi52 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 08 22:49:44 volupi52 setdatetime-helper.sh[26495]: Time is not synchronized. Attempting to sync... Jun 08 22:49:44 volupi52 sudo[26504]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 08 Jun 2025 20:49:44 GMT#015' Jun 08 22:49:44 volupi52 sudo[26504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 08 22:49:44 volupi52 setdatetime-helper.sh[26505]: Sun Jun 8 22:49:44 CEST 2025 Jun 08 22:49:44 volupi52 systemd-journald[447]: Time jumped backwards, rotating. Jun 08 22:49:44 volupi52 sudo[26504]: pam_unix(sudo:session): session closed for user root Jun 08 22:49:44 volupi52 setdatetime-helper.sh[26495]: Time synchronized successfully. Jun 08 22:49:44 volupi52 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 08 22:49:44 volupi52 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 08 22:49:50 volupi52 ntpd[1135]: PROTO: 194.58.204.148 unlink local addr 192.168.50.209 -> Jun 08 22:50:10 volupi52 volumio[1446]: info: CoreCommandRouter::volumioGetState Jun 08 22:50:12 volupi52 volumio[1446]: info: Executing endpoint metavolumio Jun 08 22:50:12 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 22:50:13 volupi52 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 08 22:50:15 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 22:50:15 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: Pool taking: 193.182.111.141 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: Pool taking: 194.58.205.20 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: Pool taking: 194.58.204.148 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: Pool taking: 13.51.141.188 Jun 08 22:50:24 volupi52 ntpd[1135]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 08 22:50:27 volupi52 volumio[1446]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Jun 08 22:50:28 volupi52 volumio[1446]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Jun 08 22:50:29 volupi52 sudo[26589]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Jun 08 22:50:29 volupi52 sudo[26589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 08 22:50:29 volupi52 sudo[26589]: pam_unix(sudo:session): session closed for user root Jun 08 22:50:29 volupi52 volumio[1446]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jun 08 22:50:29 volupi52 sudo[26593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jun 08 22:50:29 volupi52 sudo[26593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 08 22:50:29 volupi52 sudo[26593]: pam_unix(sudo:session): session closed for user root Jun 08 22:50:29 volupi52 volumio[1446]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Jun 08 22:50:29 volupi52 sudo[26596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Jun 08 22:50:29 volupi52 sudo[26596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 08 22:50:29 volupi52 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Jun 08 22:50:29 volupi52 startx[1827]: xinit: connection to X server lost Jun 08 22:50:29 volupi52 startx[1827]: Jun 08 22:50:29 volupi52 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 11 Jun 08 22:50:29 volupi52 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 125000000 DPI clock 83333333 (parent rate 125000000) Jun 08 22:50:29 volupi52 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 11 Jun 08 22:50:29 volupi52 kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 125000000 DPI clock 83333333 (parent rate 125000000) Jun 08 22:50:29 volupi52 startx[1827]: waiting for X server to shut down Jun 08 22:50:29 volupi52 startx[1828]: (II) Server terminated successfully (0). Closing log file. Jun 08 22:50:30 volupi52 startx[1827]: xinit: unexpected signal 15 Jun 08 22:50:30 volupi52 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jun 08 22:50:30 volupi52 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jun 08 22:50:30 volupi52 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Jun 08 22:50:30 volupi52 systemd[1]: volumio-kiosk.service: Consumed 21min 19.736s CPU time. Jun 08 22:50:30 volupi52 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jun 08 22:50:30 volupi52 sudo[26596]: pam_unix(sudo:session): session closed for user root Jun 08 22:50:30 volupi52 volumio[1446]: warn: [now-playing] MyBackgroundMonitor stopped Jun 08 22:50:30 volupi52 startx[26628]: X.Org X Server 1.21.1.7 Jun 08 22:50:30 volupi52 startx[26628]: X Protocol Version 11, Revision 0 Jun 08 22:50:30 volupi52 startx[26628]: Current Operating System: Linux volupi52 6.12.27-v8+ #1876 SMP PREEMPT Tue May 6 15:12:28 BST 2025 aarch64 Jun 08 22:50:30 volupi52 startx[26628]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave numa=fake=8 system_heap.max_order=0 smsc95xx.macaddr=2C:CF:67:30:03:63 vc_mem.mem_base=0x3fc00000 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=ttyAMA10,115200 console=tty1 imgpart=UUID=9b3565c3-8778-4850-ad5a-f1ed9d0b8df4 imgfile=/volumio_current.sqsh bootpart=UUID=35D9-FB53 datapart=UUID=4762520f-1b70-42fa-a450-ac1028195db2 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 Jun 08 22:50:30 volupi52 startx[26628]: xorg-server 2:21.1.7-3+rpt3+deb12u9 (https://www.debian.org/support) Jun 08 22:50:30 volupi52 startx[26628]: Current version of pixman: 0.44.0 Jun 08 22:50:30 volupi52 startx[26628]: Before reporting problems, check http://wiki.x.org Jun 08 22:50:30 volupi52 startx[26628]: to make sure that you have the latest version. Jun 08 22:50:30 volupi52 startx[26628]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 08 22:50:30 volupi52 startx[26628]: (++) from command line, (!!) notice, (II) informational, Jun 08 22:50:30 volupi52 startx[26628]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 08 22:50:30 volupi52 startx[26628]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jun 8 22:50:30 2025 Jun 08 22:50:30 volupi52 startx[26628]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 08 22:50:30 volupi52 startx[26628]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 08 22:50:30 volupi52 volumio[1446]: info: Check plugin dependencies Jun 08 22:50:30 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 22:50:30 volupi52 volumio[1446]: info: Rename folder Jun 08 22:50:30 volupi52 volumio[1446]: info: Folder /tmp/downloaded_plugin.zip removed Jun 08 22:50:30 volupi52 volumio[1446]: info: Move to category Jun 08 22:50:30 volupi52 volumio[1446]: error: Error moving plugin folder: Error: Command failed: /bin/mv /data/plugins/now_playing /data/plugins/user_interface Jun 08 22:50:30 volupi52 volumio[1446]: /bin/mv: cannot move '/data/plugins/now_playing' to '/data/plugins/user_interface/now_playing': Directory not empty Jun 08 22:50:30 volupi52 volumio[1446]: info: An error occurred installing the plugin. Rolling back config Jun 08 22:50:30 volupi52 volumio[1446]: info: Plugin folders cleanup Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning into folder /volumio/app/plugins/ Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category audio_interface Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category miscellanea Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category music_service Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category plugins.json Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category system_controller Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category user_interface Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning into folder /data/plugins/ Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category audio_interface Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category music_service Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category now_playing Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/README.md Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/config.json Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/eslint.config.mjs Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/fixup.sh Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/gitignore Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/install.sh Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/package-lock.json Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/package.json Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/tsconfig.json Jun 08 22:50:30 volupi52 volumio[1446]: info: Removing /data/plugins//now_playing/uninstall.sh Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category system_controller Jun 08 22:50:30 volupi52 volumio[1446]: info: Scanning category user_interface Jun 08 22:50:30 volupi52 volumio[1446]: info: Plugin folders cleanup completed Jun 08 22:50:30 volupi52 volumio[1446]: info: Error: Error Jun 08 22:50:30 volupi52 volumio[1446]: info: Error: Error Jun 08 22:50:30 volupi52 volumio[1446]: info: Folder /tmp/plugins removed Jun 08 22:50:30 volupi52 volumio[1446]: info: Folder /tmp/downloaded_plugin.zip removed Jun 08 22:50:30 volupi52 volumio[1446]: info: Folder /data/temp removed Jun 08 22:50:33 volupi52 volumio[1446]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 08 22:50:34 volupi52 volumio[1446]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::volumioGetState Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 22:50:34 volupi52 volumio[1446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 08 22:50:34 volupi52 volumio[1446]: info: Received Get System Info Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 22:50:34 volupi52 volumio[1446]: info: Discovery: Getting this device information Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::volumioGetState Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 22:50:34 volupi52 volumio[1446]: info: CoreCommandRouter::volumioGetState Jun 08 22:50:34 volupi52 volumio[1446]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 22:50:34 volupi52 volumio[1446]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Jun 08 22:50:34 volupi52 volumio[1446]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jun 08 22:50:34 volupi52 volumio[1446]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Jun 08 22:50:34 volupi52 volumio[1446]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) Jun 08 22:50:34 volupi52 volumio[1446]: at Socket.emit (node:events:514:28) Jun 08 22:50:34 volupi52 volumio[1446]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 08 22:50:34 volupi52 volumio[1446]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 08 22:50:34 volupi52 volumio[1446]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 22:50:35 volupi52 sudo[26887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-08 22:49' Jun 08 22:50:35 volupi52 sudo[26887]: 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"