Dec 17 14:37:21 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Dec 17 14:37:22 volumio dbus-daemon[552]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2274' (uid=0 pid=29462 comm="timedatectl show --property=NTPSynchronized --valu") Dec 17 14:37:22 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 17 14:37:22 volumio dbus-daemon[552]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 17 14:37:22 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 17 14:37:22 volumio setdatetime-helper.sh[29461]: Time is already synchronized. Dec 17 14:37:22 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Dec 17 14:37:22 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Dec 17 14:37:52 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 17 14:38:19 volumio sudo[29671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:38:19 volumio sudo[29671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:19 volumio sudo[29671]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:19 volumio sudo[29673]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:38:19 volumio sudo[29673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:19 volumio sudo[29673]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:19 volumio volumio[6810]: verbose: New Socket.io Connection to 192.168.15.21 from 192.168.15.12 UA: Mozilla/5.0 (X11; Linux x86_64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 17 14:38:19 volumio sudo[29677]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:38:19 volumio sudo[29677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:19 volumio sudo[29679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:38:19 volumio sudo[29677]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:19 volumio sudo[29679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:19 volumio sudo[29679]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:19 volumio volumio[6810]: verbose: New Socket.io Connection to 192.168.15.21 from 192.168.15.12 UA: Mozilla/5.0 (X11; Linux x86_64; rv:145.0) Gecko/20100101 Firefox/145.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::volumioGetQueue Dec 17 14:38:20 volumio volumio[6810]: info: CoreStateMachine::getQueue Dec 17 14:38:20 volumio volumio[6810]: info: CorePlayQueue::getQueue Dec 17 14:38:20 volumio volumio[6810]: info: Listing playlists Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 14:38:20 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:20 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 14:38:20 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 14:38:21 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:21 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:21 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:23 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 14:38:23 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:23 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:23 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:23 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:23 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:23 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:29 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 14:38:30 volumio sudo[29720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:38:30 volumio sudo[29720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:30 volumio sudo[29720]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:30 volumio sudo[29722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:38:30 volumio sudo[29722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:30 volumio sudo[29722]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:30 volumio volumio[6810]: verbose: New Socket.io Connection to 192.168.15.21 from 192.168.15.42 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 17 14:38:31 volumio sudo[29726]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:38:31 volumio sudo[29726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:31 volumio sudo[29726]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:31 volumio sudo[29728]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:38:31 volumio sudo[29728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 14:38:31 volumio sudo[29728]: pam_unix(sudo:session): session closed for user root Dec 17 14:38:31 volumio volumio[6810]: verbose: New Socket.io Connection to 192.168.15.21 from 192.168.15.42 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 14:38:31 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:31 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:31 volumio volumio[6810]: info: Listing playlists Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 14:38:31 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 14:38:32 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:32 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 14:38:33 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:33 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::volumioGetQueue Dec 17 14:38:33 volumio volumio[6810]: info: CoreStateMachine::getQueue Dec 17 14:38:33 volumio volumio[6810]: info: CorePlayQueue::getQueue Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 14:38:33 volumio volumio[6810]: info: Received Get System Info Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 14:38:33 volumio volumio[6810]: info: Discovery: Getting this device information Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Dec 17 14:38:33 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 14:38:38 volumio volumio[6810]: Searching all installed plugins Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: , search Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 17 14:38:38 volumio volumio[6810]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 17 14:38:38 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Dec 17 14:38:43 volumio volumio[6810]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Dec 17 14:38:43 volumio volumio[6810]: info: [ytmusic] Innertube support service: Start service with Node Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 14:38:43 volumio volumio[6810]: Searching all installed plugins Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: , search Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 17 14:38:43 volumio volumio[6810]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 17 14:38:43 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Dec 17 14:38:43 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Dec 17 14:38:43 volumio dbus-daemon[552]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2276' (uid=0 pid=29819 comm="timedatectl show --property=NTPSynchronized --valu") Dec 17 14:38:43 volumio volumio[6810]: Upnp client error: Error: This socket has been ended by the other party Dec 17 14:38:43 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 17 14:38:43 volumio volumio[6810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:38:43 volumio dbus-daemon[552]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 17 14:38:43 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 17 14:38:43 volumio setdatetime-helper.sh[29806]: Time is already synchronized. Dec 17 14:38:43 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Dec 17 14:38:43 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Dec 17 14:38:43 volumio volumio[6810]: TypeError: Cannot read properties of undefined (reading 'length') Dec 17 14:38:43 volumio volumio[6810]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Dec 17 14:38:43 volumio volumio[6810]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Dec 17 14:38:43 volumio volumio[6810]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Dec 17 14:38:43 volumio volumio[6810]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 17 14:38:43 volumio volumio[6810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:38:44 volumio sudo[29849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 14:37' Dec 17 14:38:44 volumio sudo[29849]: 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"