May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetVisibleSources May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 18:58:00 volumio1920x1080 volumio[1071]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 23 18:58:00 volumio1920x1080 volumio[1071]: info: Received Get System Info May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:00 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:00 volumio1920x1080 volumio[1071]: info: Listing playlists May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 18:58:00 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:58:01 volumio1920x1080 volumio[1071]: info: Received Get System Info May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:01 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:01 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:58:02 volumio1920x1080 volumio[1071]: info: Received Get System Info May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:02 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:02 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:03 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:58:03 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 23 18:58:05 volumio1920x1080 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 23 18:58:09 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 23 18:58:18 volumio1920x1080 volumio[1071]: info: Received Get System Version May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 18:58:18 volumio1920x1080 volumio[1071]: info: Received Get System Info May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:18 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:18 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:23 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:23 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:52 volumio1920x1080 volumio[1071]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf May 23 18:58:53 volumio1920x1080 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 18:58:53 volumio1920x1080 dbus-daemon[591]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.144' (uid=0 pid=12275 comm="timedatectl show --property=NTPSynchronized --valu") May 23 18:58:53 volumio1920x1080 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 18:58:53 volumio1920x1080 dbus-daemon[591]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 18:58:53 volumio1920x1080 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 18:58:53 volumio1920x1080 setdatetime-helper.sh[12272]: Time is already synchronized. May 23 18:58:53 volumio1920x1080 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 18:58:53 volumio1920x1080 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 18:58:53 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:58:53 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:58:53 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:58:53 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:58:53 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:58:53 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:58:55 volumio1920x1080 volumio[1071]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf May 23 18:58:55 volumio1920x1080 volumio[1071]: info: Folder /tmp/plugins removed May 23 18:58:56 volumio1920x1080 volumio[1071]: info: Check plugin dependencies May 23 18:58:56 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 18:58:56 volumio1920x1080 volumio[1071]: info: Checking if plugin already exists May 23 18:58:56 volumio1920x1080 volumio[1071]: info: Rename folder May 23 18:58:57 volumio1920x1080 volumio[1071]: info: Folder /tmp/downloaded_plugin.zip removed May 23 18:58:57 volumio1920x1080 volumio[1071]: info: Move to category May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Checking if install.sh is present May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Executing install.sh May 23 18:58:58 volumio1920x1080 sudo[12294]: volumio : unable to resolve host volumio1920x1080: System error May 23 18:58:58 volumio1920x1080 sudo[12294]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh May 23 18:58:58 volumio1920x1080 sudo[12294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 18:58:58 volumio1920x1080 sudo[12294]: pam_unix(sudo:session): session closed for user root May 23 18:58:58 volumio1920x1080 volumio[1071]: info: YouTube Music plugin installed May 23 18:58:58 volumio1920x1080 volumio[1071]: Plugin install end detected on script May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Install script completed May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Adding reference to registry May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Done installing plugin. May 23 18:58:58 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:58:58 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Folder /tmp/plugins removed May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Folder /tmp/downloaded_plugin.zip removed May 23 18:58:58 volumio1920x1080 volumio[1071]: info: Folder /data/temp removed May 23 18:59:11 volumio1920x1080 volumio[1071]: info: Enabling plugin ytmusic May 23 18:59:11 volumio1920x1080 volumio[1071]: info: Loading plugin "ytmusic"... May 23 18:59:13 volumio1920x1080 volumio[1071]: info: PLUGIN START: ytmusic May 23 18:59:13 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 23 18:59:13 volumio1920x1080 volumio[1071]: info: [1748019553451] CoreMusicLibrary::Adding element YouTube Music May 23 18:59:13 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 18:59:13 volumio1920x1080 volumio[1071]: Cannot find translation for source YouTube Music May 23 18:59:13 volumio1920x1080 volumio[1071]: info: Done. May 23 18:59:23 volumio1920x1080 systemd[1]: systemd-timedated.service: Deactivated successfully. May 23 18:59:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 18:59:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 18:59:23 volumio1920x1080 volumio[1071]: info: Discovery: Getting this device information May 23 18:59:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioGetState May 23 18:59:23 volumio1920x1080 volumio[1071]: info: CorePlayQueue::getTrack 15 May 23 18:59:23 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 18:59:27 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:59:27 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 23 18:59:34 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 18:59:34 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: creating Innertube instance... May 23 18:59:39 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... May 23 18:59:41 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 23 18:59:41 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token May 23 18:59:41 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... May 23 18:59:41 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 18:59:41 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 23 18:59:44 volumio1920x1080 volumio[1071]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds May 23 18:59:44 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music May 23 18:59:44 volumio1920x1080 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 18:59:44 volumio1920x1080 volumio[1071]: info: Disabling plugin ytmusic May 23 18:59:44 volumio1920x1080 volumio[1071]: info: Done. May 23 18:59:45 volumio1920x1080 volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 18:59:45 volumio1920x1080 volumio[1071]: TypeError: Cannot read properties of null (reading 'logger') May 23 18:59:45 volumio1920x1080 volumio[1071]: at YTMusicContext.getLogger (/data/plugins/music_service/ytmusic/dist/lib/YTMusicContext.js:65:80) May 23 18:59:45 volumio1920x1080 volumio[1071]: at /data/plugins/music_service/ytmusic/dist/index.js:140:38 May 23 18:59:45 volumio1920x1080 volumio[1071]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 23 18:59:45 volumio1920x1080 volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 18:59:45 volumio1920x1080 sudo[12385]: volumio : unable to resolve host volumio1920x1080: System error May 23 18:59:45 volumio1920x1080 sudo[12385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-23 18:58' May 23 18:59:45 volumio1920x1080 sudo[12385]: 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"