May 23 16:43:27 volumio1920x1080 systemd[1]: systemd-timedated.service: Deactivated successfully. May 23 16:43:44 volumio1920x1080 volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf May 23 16:43:47 volumio1920x1080 volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf May 23 16:43:47 volumio1920x1080 volumio[1261]: info: Folder /tmp/plugins removed May 23 16:43:48 volumio1920x1080 volumio[1261]: info: Check plugin dependencies May 23 16:43:48 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 16:43:48 volumio1920x1080 volumio[1261]: info: Checking if plugin already exists May 23 16:43:48 volumio1920x1080 volumio[1261]: info: Rename folder May 23 16:43:48 volumio1920x1080 volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 23 16:43:48 volumio1920x1080 volumio[1261]: info: Move to category May 23 16:43:49 volumio1920x1080 volumio[1261]: info: Checking if install.sh is present May 23 16:43:49 volumio1920x1080 volumio[1261]: info: Executing install.sh May 23 16:43:49 volumio1920x1080 sudo[3244]: volumio : unable to resolve host volumio1920x1080: System error May 23 16:43:49 volumio1920x1080 sudo[3244]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh May 23 16:43:49 volumio1920x1080 sudo[3244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 16:43:49 volumio1920x1080 volumio[1261]: info: Installing geo-tz node dependency May 23 16:43:55 volumio1920x1080 ntpd[915]: PROTO: 91.212.242.20 unlink local addr 192.168.1.99 -> May 23 16:43:58 volumio1920x1080 volumio[1261]: info: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: added 26 packages, changed 4 packages, and audited 166 packages in 7s May 23 16:43:58 volumio1920x1080 volumio[1261]: info: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: 23 packages are looking for funding May 23 16:43:58 volumio1920x1080 volumio[1261]: info: run `npm fund` for details May 23 16:43:58 volumio1920x1080 volumio[1261]: info: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: 2 high severity vulnerabilities May 23 16:43:58 volumio1920x1080 volumio[1261]: info: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: To address all issues, run: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: npm audit fix May 23 16:43:58 volumio1920x1080 volumio[1261]: info: May 23 16:43:58 volumio1920x1080 volumio[1261]: info: Run `npm audit` for details. May 23 16:44:01 volumio1920x1080 volumio[1261]: info: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: up to date, audited 166 packages in 2s May 23 16:44:01 volumio1920x1080 volumio[1261]: info: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: 23 packages are looking for funding May 23 16:44:01 volumio1920x1080 volumio[1261]: info: run `npm fund` for details May 23 16:44:01 volumio1920x1080 volumio[1261]: info: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: 2 high severity vulnerabilities May 23 16:44:01 volumio1920x1080 volumio[1261]: info: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: To address all issues, run: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: npm audit fix May 23 16:44:01 volumio1920x1080 volumio[1261]: info: May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Run `npm audit` for details. May 23 16:44:01 volumio1920x1080 sudo[3244]: pam_unix(sudo:session): session closed for user root May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Install script completed May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Adding reference to registry May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Done installing plugin. May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Folder /tmp/plugins removed May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Folder /data/temp removed May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Creating user directories (if not exist) May 23 16:44:01 volumio1920x1080 volumio[1261]: info: Now Playing plugin installed May 23 16:44:01 volumio1920x1080 volumio[1261]: Plugin install end detected on script May 23 16:44:16 volumio1920x1080 ntpd[915]: PROTO: 213.222.217.10 unlink local addr 192.168.1.99 -> May 23 16:44:16 volumio1920x1080 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 16:44:16 volumio1920x1080 dbus-daemon[642]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.49' (uid=0 pid=3360 comm="timedatectl show --property=NTPSynchronized --valu") May 23 16:44:16 volumio1920x1080 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 16:44:17 volumio1920x1080 dbus-daemon[642]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 16:44:17 volumio1920x1080 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 16:44:17 volumio1920x1080 setdatetime-helper.sh[3359]: Time is already synchronized. May 23 16:44:17 volumio1920x1080 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 16:44:17 volumio1920x1080 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 16:44:19 volumio1920x1080 ntpd[915]: PROTO: 193.25.222.136 unlink local addr 192.168.1.99 -> May 23 16:44:25 volumio1920x1080 volumio[1261]: info: Enabling plugin now_playing May 23 16:44:25 volumio1920x1080 volumio[1261]: info: Loading plugin "now_playing"... May 23 16:44:26 volumio1920x1080 volumio[1261]: info: PLUGIN START: now_playing May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] ConfigUpdater: config is up to date. May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] Fetching API key... May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] App is listening on port 4004. May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak May 23 16:44:26 volumio1920x1080 sudo[3389]: volumio : unable to resolve host volumio1920x1080: System error May 23 16:44:26 volumio1920x1080 sudo[3389]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh May 23 16:44:26 volumio1920x1080 sudo[3389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 16:44:26 volumio1920x1080 sudo[3389]: pam_unix(sudo:session): session closed for user root May 23 16:44:26 volumio1920x1080 volumio[1261]: sudo: unable to resolve host volumio1920x1080: System error May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk May 23 16:44:26 volumio1920x1080 sudo[3392]: volumio : unable to resolve host volumio1920x1080: System error May 23 16:44:26 volumio1920x1080 sudo[3392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk May 23 16:44:26 volumio1920x1080 sudo[3392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 16:44:26 volumio1920x1080 sudo[3392]: pam_unix(sudo:session): session closed for user root May 23 16:44:26 volumio1920x1080 volumio[1261]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk May 23 16:44:26 volumio1920x1080 sudo[3395]: volumio : unable to resolve host volumio1920x1080: System error May 23 16:44:26 volumio1920x1080 sudo[3395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk May 23 16:44:26 volumio1920x1080 sudo[3395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 16:44:26 volumio1920x1080 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... May 23 16:44:26 volumio1920x1080 startx[1628]: xinit: connection to X server lost May 23 16:44:26 volumio1920x1080 startx[1628]: May 23 16:44:27 volumio1920x1080 startx[1628]: waiting for X server to shut down May 23 16:44:27 volumio1920x1080 startx[1629]: (II) Server terminated successfully (0). Closing log file. May 23 16:44:27 volumio1920x1080 volumio[1261]: info: [now-playing] OpenWeatherMap API key obtained. May 23 16:44:27 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 16:44:27 volumio1920x1080 volumio[1261]: info: Discovery: Getting this device information May 23 16:44:27 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::volumioGetState May 23 16:44:27 volumio1920x1080 volumio[1261]: info: CorePlayQueue::getTrack 1 May 23 16:44:27 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 16:44:27 volumio1920x1080 startx[1628]: xinit: unexpected signal 15 May 23 16:44:27 volumio1920x1080 startx[3400]: xauth: (argv):1: bad display name "volumio1920x1080:0" in "remove" command May 23 16:44:27 volumio1920x1080 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE May 23 16:44:27 volumio1920x1080 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. May 23 16:44:27 volumio1920x1080 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. May 23 16:44:27 volumio1920x1080 systemd[1]: volumio-kiosk.service: Consumed 1min 45.036s CPU time. May 23 16:44:27 volumio1920x1080 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. May 23 16:44:27 volumio1920x1080 sudo[3395]: pam_unix(sudo:session): session closed for user root May 23 16:44:27 volumio1920x1080 volumio[1261]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 23 16:44:27 volumio1920x1080 volumio[1261]: info: Done. May 23 16:44:28 volumio1920x1080 volumio[1261]: info: [now-playing] MyBackgroundMonitor captured 'add': POGODA1.jpg May 23 16:44:28 volumio1920x1080 startx[3411]: hostname: System error May 23 16:44:28 volumio1920x1080 startx[3423]: xauth: (stdin):1: bad display name "volumio1920x1080:0" in "add" command May 23 16:44:28 volumio1920x1080 startx[3425]: X.Org X Server 1.21.1.7 May 23 16:44:28 volumio1920x1080 startx[3425]: X Protocol Version 11, Revision 0 May 23 16:44:28 volumio1920x1080 startx[3425]: Current Operating System: Linux volumio1920x1080 6.12.27-v7l+ #1876 SMP Tue May 6 15:10:05 BST 2025 armv7l May 23 16:44:28 volumio1920x1080 startx[3425]: 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 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=E4:5F:01:92:A3:49 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=cfcca11c-dc04-4b76-9dd1-1661f23c4afd imgfile=/volumio_current.sqsh bootpart=UUID=8F33-7140 datapart=UUID=ab35aa80-11a5-4cc4-9613-f1b41ff0cf23 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 May 23 16:44:28 volumio1920x1080 startx[3425]: xorg-server 2:21.1.7-3+rpt3+deb12u9 (https://www.debian.org/support) May 23 16:44:28 volumio1920x1080 startx[3425]: Current version of pixman: 0.44.0 May 23 16:44:28 volumio1920x1080 startx[3425]: Before reporting problems, check http://wiki.x.org May 23 16:44:28 volumio1920x1080 startx[3425]: to make sure that you have the latest version. May 23 16:44:28 volumio1920x1080 startx[3425]: Markers: (--) probed, (**) from config file, (==) default setting, May 23 16:44:28 volumio1920x1080 startx[3425]: (++) from command line, (!!) notice, (II) informational, May 23 16:44:28 volumio1920x1080 startx[3425]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 23 16:44:28 volumio1920x1080 startx[3425]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri May 23 16:44:28 2025 May 23 16:44:28 volumio1920x1080 startx[3425]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 23 16:44:28 volumio1920x1080 startx[3425]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 23 16:44:31 volumio1920x1080 volumio[1261]: verbose: New Socket.io Connection to localhost: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: 9 May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 23 16:44:31 volumio1920x1080 volumio[1261]: info: Listing playlists May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::volumioGetQueue May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreStateMachine::getQueue May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CorePlayQueue::getQueue May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::volumioGetVisibleSources May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CoreCommandRouter::volumioGetState May 23 16:44:31 volumio1920x1080 volumio[1261]: info: CorePlayQueue::getTrack 1 May 23 16:44:47 volumio1920x1080 systemd[1]: systemd-timedated.service: Deactivated successfully. May 23 16:44:58 volumio1920x1080 volumio[1261]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 16:44:58 volumio1920x1080 volumio[1261]: TypeError: Cannot read properties of undefined (reading 'plugin_type') May 23 16:44:58 volumio1920x1080 volumio[1261]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) May 23 16:44:58 volumio1920x1080 volumio[1261]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) May 23 16:44:58 volumio1920x1080 volumio[1261]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) May 23 16:44:58 volumio1920x1080 volumio[1261]: at Socket.emit (node:events:514:28) May 23 16:44:58 volumio1920x1080 volumio[1261]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 23 16:44:58 volumio1920x1080 volumio[1261]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 23 16:44:58 volumio1920x1080 volumio[1261]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 16:44:58 volumio1920x1080 sudo[3700]: volumio : unable to resolve host volumio1920x1080: System error May 23 16:44:58 volumio1920x1080 sudo[3700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-23 16:43' May 23 16:44:58 volumio1920x1080 sudo[3700]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"