Jun 28 11:46:01 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 28 11:46:02 volumio volumio[960]: info: Checking if install.sh is present Jun 28 11:46:02 volumio dbus-daemon[622]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.51' (uid=0 pid=3530 comm="timedatectl show --property=NTPSynchronized --valu") Jun 28 11:46:02 volumio volumio[960]: info: Executing install.sh Jun 28 11:46:02 volumio sudo[3536]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/install.sh Jun 28 11:46:02 volumio sudo[3536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 11:46:02 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 28 11:46:02 volumio sudo[3536]: pam_unix(sudo:session): session closed for user root Jun 28 11:46:02 volumio volumio[960]: info: YouTube2 plugin installed Jun 28 11:46:02 volumio volumio[960]: Plugin install end detected on script Jun 28 11:46:02 volumio volumio[960]: info: Install script completed Jun 28 11:46:02 volumio volumio[960]: info: Adding reference to registry Jun 28 11:46:02 volumio volumio[960]: info: Done installing plugin. Jun 28 11:46:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 11:46:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 11:46:02 volumio volumio[960]: info: Folder /tmp/plugins removed Jun 28 11:46:02 volumio volumio[960]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 11:46:02 volumio volumio[960]: info: Folder /data/temp removed Jun 28 11:46:02 volumio dbus-daemon[622]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 28 11:46:02 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 28 11:46:02 volumio setdatetime-helper.sh[3529]: Time is not synchronized. Attempting to sync... Jun 28 11:46:02 volumio sudo[3543]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 28 Jun 2025 09:46:03 GMT#015' Jun 28 11:46:02 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 28 11:46:03 volumio setdatetime-helper.sh[3544]: Sat Jun 28 11:46:03 CEST 2025 Jun 28 11:46:03 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Jun 28 11:46:03 volumio setdatetime-helper.sh[3529]: Time synchronized successfully. Jun 28 11:46:03 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 28 11:46:03 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 28 11:46:03 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Jun 28 11:46:04 volumio volumio[960]: info: Enabling plugin youtube2 Jun 28 11:46:04 volumio volumio[960]: info: Loading plugin "youtube2"... Jun 28 11:46:05 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Jun 28 11:46:05 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Jun 28 11:46:05 volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.727s CPU time. Jun 28 11:46:09 volumio volumio[960]: info: PLUGIN START: youtube2 Jun 28 11:46:09 volumio volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 11:46:09 volumio volumio[960]: info: [1751103969871] CoreMusicLibrary::Adding element YouTube2 Jun 28 11:46:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 11:46:09 volumio volumio[960]: Cannot find translation for source YouTube2 Jun 28 11:46:09 volumio volumio[960]: info: Done. Jun 28 11:46:09 volumio volumio[960]: info: Enabling plugin youtube2 Jun 28 11:46:09 volumio volumio[960]: info: Loading plugin "youtube2"... Jun 28 11:46:09 volumio volumio[960]: info: PLUGIN START: youtube2 Jun 28 11:46:09 volumio volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 11:46:09 volumio volumio[960]: info: [1751103969908] CoreMusicLibrary::Adding element YouTube2 Jun 28 11:46:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 11:46:09 volumio volumio[960]: Cannot find translation for source YouTube2 Jun 28 11:46:09 volumio volumio[960]: info: Done. Jun 28 11:46:15 volumio volumio[960]: info: Starting Uninstall of plugin music_service - ytcr Jun 28 11:46:15 volumio volumio[960]: info: Uninstalling plugin ytcr Jun 28 11:46:15 volumio volumio[960]: info: Disabling plugin ytcr Jun 28 11:46:15 volumio volumio[960]: info: Checking if uninstall.sh is present Jun 28 11:46:15 volumio volumio[960]: info: Executing uninstall.sh Jun 28 11:46:15 volumio sudo[3619]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytcr/uninstall.sh Jun 28 11:46:15 volumio sudo[3619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 11:46:15 volumio sudo[3619]: pam_unix(sudo:session): session closed for user root Jun 28 11:46:15 volumio volumio[960]: info: Uninstall script completed Jun 28 11:46:15 volumio volumio[960]: info: Removing plugin ytcr from configuration Jun 28 11:46:15 volumio volumio[960]: info: Successfully removed ytcr configuration files Jun 28 11:46:15 volumio volumio[960]: info: Plugin folders cleanup Jun 28 11:46:15 volumio volumio[960]: info: Scanning into folder /volumio/app/plugins/ Jun 28 11:46:15 volumio volumio[960]: info: Scanning category audio_interface Jun 28 11:46:15 volumio volumio[960]: info: Scanning category miscellanea Jun 28 11:46:15 volumio volumio[960]: info: Scanning category music_service Jun 28 11:46:15 volumio volumio[960]: info: Scanning category plugins.json Jun 28 11:46:15 volumio volumio[960]: info: Scanning category system_controller Jun 28 11:46:15 volumio volumio[960]: info: Scanning category user_interface Jun 28 11:46:15 volumio volumio[960]: info: Scanning into folder /data/plugins/ Jun 28 11:46:15 volumio volumio[960]: info: Scanning category music_service Jun 28 11:46:15 volumio volumio[960]: info: Cleaning folder for ytcr Jun 28 11:46:19 volumio volumio[960]: info: Plugin folders cleanup completed Jun 28 11:46:19 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 11:46:19 volumio volumio[960]: info: [{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"2.3.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Jun 28 11:46:19 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 11:46:26 volumio volumio[960]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/bookworm/armhf Jun 28 11:46:29 volumio volumio[960]: error: error Jun 28 11:46:31 volumio volumio[960]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/bookworm/armhf Jun 28 11:46:31 volumio volumio[960]: info: Folder /tmp/plugins removed Jun 28 11:46:32 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 28 11:46:38 volumio volumio[960]: info: Check plugin dependencies Jun 28 11:46:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 11:46:38 volumio volumio[960]: info: Checking if plugin already exists Jun 28 11:46:38 volumio volumio[960]: info: Rename folder Jun 28 11:46:38 volumio volumio[960]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 11:46:38 volumio volumio[960]: info: Move to category Jun 28 11:46:47 volumio volumio[960]: info: Checking if install.sh is present Jun 28 11:46:47 volumio volumio[960]: info: Executing install.sh Jun 28 11:46:47 volumio sudo[3704]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytcr/install.sh Jun 28 11:46:47 volumio sudo[3704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 11:46:47 volumio sudo[3704]: pam_unix(sudo:session): session closed for user root Jun 28 11:46:47 volumio volumio[960]: info: Install script completed Jun 28 11:46:47 volumio volumio[960]: info: Adding reference to registry Jun 28 11:46:47 volumio volumio[960]: info: Done installing plugin. Jun 28 11:46:47 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 11:46:47 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 11:46:47 volumio volumio[960]: info: YouTube Cast Receiver installed Jun 28 11:46:47 volumio volumio[960]: Plugin install end detected on script Jun 28 11:46:47 volumio volumio[960]: info: Folder /tmp/plugins removed Jun 28 11:46:47 volumio volumio[960]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 11:46:47 volumio volumio[960]: info: Folder /data/temp removed Jun 28 11:46:49 volumio volumio[960]: info: Enabling plugin ytcr Jun 28 11:46:49 volumio volumio[960]: info: Loading plugin "ytcr"... Jun 28 11:46:55 volumio volumio[960]: info: PLUGIN START: ytcr Jun 28 11:46:56 volumio volumio[960]: info: Enabling plugin ytcr Jun 28 11:46:56 volumio volumio[960]: info: Loading plugin "ytcr"... Jun 28 11:46:56 volumio volumio[960]: info: PLUGIN START: ytcr Jun 28 11:46:57 volumio volumio[960]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 28 11:46:57 volumio volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 11:46:57 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jun 28 11:46:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jun 28 11:46:57 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jun 28 11:46:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jun 28 11:46:57 volumio volumio[960]: info: CoreStateMachine::pushState Jun 28 11:46:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jun 28 11:46:57 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jun 28 11:46:57 volumio volumio[960]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Jun 28 11:46:57 volumio volumio[960]: (TypeError) Cannot read properties of undefined (reading 'vol') Jun 28 11:46:57 volumio volumio[960]: TypeError: Cannot read properties of undefined (reading 'vol') Jun 28 11:46:57 volumio volumio[960]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Jun 28 11:46:57 volumio volumio[960]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 28 11:46:57 volumio volumio[960]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Jun 28 11:46:57 volumio volumio[960]: at async /data/plugins/music_service/ytcr/dist/index.js:330:13 Jun 28 11:46:57 volumio volumio[960]: error: [yt-cast-receiver] Failed to start receiver: Jun 28 11:46:57 volumio volumio[960]: (DialServerError) Failed to start DIAL server Jun 28 11:46:57 volumio volumio[960]: --->(Error) listen EADDRINUSE: address already in use :::8098 Jun 28 11:46:57 volumio volumio[960]: Stack trace: Jun 28 11:46:57 volumio volumio[960]: DialServerError: Failed to start DIAL server Jun 28 11:46:57 volumio volumio[960]: at Server. (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/dial/DialServer.js:103:28) Jun 28 11:46:57 volumio volumio[960]: at Server.emit (node:events:514:28) Jun 28 11:46:57 volumio volumio[960]: at emitErrorNT (node:net:1899:8) Jun 28 11:46:57 volumio volumio[960]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Jun 28 11:46:57 volumio volumio[960]: info: [yt-cast-receiver] Player.reset() Jun 28 11:46:57 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jun 28 11:46:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jun 28 11:46:57 volumio volumio[960]: info: Done. Jun 28 11:46:59 volumio volumio[960]: info: [yt-cast-receiver] Player.reset() Jun 28 11:46:59 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jun 28 11:46:59 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jun 28 11:46:59 volumio volumio[960]: info: Disabling plugin ytcr Jun 28 11:46:59 volumio volumio[960]: info: Done. Jun 28 11:47:00 volumio volumio[960]: error: [ytcr] Failed to start plugin: Jun 28 11:47:00 volumio volumio[960]: (DialServerError) Failed to start DIAL server Jun 28 11:47:00 volumio volumio[960]: --->(Error) listen EADDRINUSE: address already in use :::8098 Jun 28 11:47:00 volumio volumio[960]: Stack trace: Jun 28 11:47:00 volumio volumio[960]: DialServerError: Failed to start DIAL server Jun 28 11:47:00 volumio volumio[960]: at Server. (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/dial/DialServer.js:103:28) Jun 28 11:47:00 volumio volumio[960]: at Server.emit (node:events:514:28) Jun 28 11:47:00 volumio volumio[960]: at emitErrorNT (node:net:1899:8) Jun 28 11:47:00 volumio volumio[960]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Jun 28 11:47:00 volumio volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 11:47:00 volumio volumio[960]: TypeError: Cannot read properties of null (reading 'coreCommand') Jun 28 11:47:00 volumio volumio[960]: at YTCRContext.toast (/data/plugins/music_service/ytcr/dist/lib/YTCRContext.js:54:70) Jun 28 11:47:00 volumio volumio[960]: at /data/plugins/music_service/ytcr/dist/index.js:342:42 Jun 28 11:47:00 volumio volumio[960]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 28 11:47:00 volumio volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 11:47:01 volumio sudo[3734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-28 11:46' Jun 28 11:47:01 volumio sudo[3734]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"