Oct 26 07:51:04 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Oct 26 07:51:05 volumio dbus-daemon[737]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.376' (uid=0 pid=29124 comm="timedatectl show --property=NTPSynchronized --valu")
Oct 26 07:51:05 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Oct 26 07:51:05 volumio dbus-daemon[737]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 26 07:51:05 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
Oct 26 07:51:05 volumio setdatetime-helper.sh[29123]: Time is already synchronized.
Oct 26 07:51:05 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Oct 26 07:51:05 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Oct 26 07:51:06 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 07:51:06 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 26 07:51:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 07:51:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 26 07:51:35 volumio systemd[1]: systemd-timedated.service: Deactivated successfully.
Oct 26 07:51:50 volumio volumio[1215]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf
Oct 26 07:51:54 volumio volumio[1215]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf
Oct 26 07:51:54 volumio volumio[1215]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music
Oct 26 07:51:54 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 26 07:51:57 volumio volumio[1215]: info: Check plugin dependencies
Oct 26 07:51:57 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 26 07:51:57 volumio volumio[1215]: info: Rename folder
Oct 26 07:51:57 volumio volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed
Oct 26 07:51:57 volumio volumio[1215]: info: Move to category
Oct 26 07:52:12 volumio volumio[1215]: info: Checking if install.sh is present
Oct 26 07:52:12 volumio volumio[1215]: info: Executing install.sh
Oct 26 07:52:12 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.132 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Oct 26 07:52:12 volumio sudo[29267]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh
Oct 26 07:52:12 volumio sudo[29267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 26 07:52:12 volumio sudo[29267]: pam_unix(sudo:session): session closed for user root
Oct 26 07:52:12 volumio volumio[1215]: info: YouTube Music plugin installed
Oct 26 07:52:12 volumio volumio[1215]: Plugin install end detected on script
Oct 26 07:52:12 volumio volumio[1215]: info: Install script completed
Oct 26 07:52:12 volumio volumio[1215]: info: Done installing plugin.
Oct 26 07:52:12 volumio volumio[1215]: info: Enabling plugin ytmusic
Oct 26 07:52:12 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 07:52:12 volumio volumio[1215]: info: [{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"2.3.4","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.3.5","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"4.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bluetooth_Remote","name":"Bluetoothremote","category":"system_hardware","version":"0.0.12","icon":"fa-bluetooth","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.10","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}]
Oct 26 07:52:12 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 26 07:52:12 volumio volumio[1215]: info: Folder /tmp/plugins removed
Oct 26 07:52:12 volumio volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed
Oct 26 07:52:12 volumio volumio[1215]: info: Folder /data/temp removed
Oct 26 07:52:37 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.132 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Oct 26 07:52:47 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 07:52:47 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 26 07:52:47 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Oct 26 07:52:47 volumio dbus-daemon[737]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.378' (uid=0 pid=29337 comm="timedatectl show --property=NTPSynchronized --valu")
Oct 26 07:52:47 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Oct 26 07:52:47 volumio dbus-daemon[737]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 26 07:52:47 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
Oct 26 07:52:47 volumio setdatetime-helper.sh[29336]: Time is already synchronized.
Oct 26 07:52:47 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Oct 26 07:52:47 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 07:52:52 volumio volumio[1215]: info: Discovery: Getting this device information
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 07:52:52 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.141 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Oct 26 07:52:52 volumio volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 07:52:52 volumio volumio[1215]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 26 07:52:52 volumio volumio[1215]: TypeError: Cannot read properties of null (reading 'logger')
Oct 26 07:52:52 volumio volumio[1215]:     at YTMusicContext.getLogger (/data/plugins/music_service/ytmusic/dist/lib/YTMusicContext.js:65:80)
Oct 26 07:52:52 volumio volumio[1215]:     at /data/plugins/music_service/ytmusic/dist/index.js:140:38
Oct 26 07:52:52 volumio volumio[1215]:     at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Oct 26 07:52:52 volumio volumio[1215]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 26 07:52:53 volumio sudo[29371]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-26 07:51'
Oct 26 07:52:53 volumio sudo[29371]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025"
VOLUMIO_VERSION="4.022"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"