Nov 01 23:18:14 volumio sudo[13919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 23:18:14 volumio sudo[13919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 23:18:14 volumio sudo[13921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 23:18:14 volumio sudo[13919]: pam_unix(sudo:session): session closed for user root Nov 01 23:18:14 volumio sudo[13921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 23:18:14 volumio sudo[13921]: pam_unix(sudo:session): session closed for user root Nov 01 23:18:14 volumio volumio[1131]: verbose: New Socket.io Connection to 192.168.0.15 from 192.168.0.13 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Whale/4.34.340.19 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 23:18:14 volumio volumio[1131]: info: Received Get System Info Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:18:14 volumio volumio[1131]: info: Discovery: Getting this device information Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:14 volumio volumio[1131]: info: Listing playlists Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:18:14 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 01 23:18:15 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:15 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 01 23:18:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:18:16 volumio volumio[1131]: info: Received Get System Info Nov 01 23:18:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:18:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:18:16 volumio volumio[1131]: info: Discovery: Getting this device information Nov 01 23:18:16 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:18:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:18:17 volumio volumio[1131]: info: Received Get System Info Nov 01 23:18:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:18:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:18:17 volumio volumio[1131]: info: Discovery: Getting this device information Nov 01 23:18:17 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:18:18 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:18:18 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 23:18:24 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 23:18:29 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:18:35 volumio volumio[1131]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 01 23:18:35 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:35.536+09:00 level=DEBUG msg="received event" component=volumio/socket event=pushBackendEventsStatus Nov 01 23:18:35 volumio volumio[1131]: info: Received Get System Version Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:18:35 volumio volumio[1131]: info: Received Get System Info Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:18:35 volumio volumio[1131]: info: Discovery: Getting this device information Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:18:35 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:18:36 volumio volumio[1131]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/youtube2/volumio/bookworm/armhf Nov 01 23:18:36 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:36.482+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:36 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:36.483+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:43 volumio volumio[1131]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/youtube2/volumio/bookworm/armhf Nov 01 23:18:43 volumio volumio[1131]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Nov 01 23:18:43 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 23:18:43 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:43.217+09:00 level=DEBUG msg="received event" component=volumio/socket event=pushBrowseSources Nov 01 23:18:43 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:43.222+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:43 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:43.223+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:43 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:43.224+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:43 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:43.225+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.848+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.848+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:44 volumio volumio[1131]: info: Check plugin dependencies Nov 01 23:18:44 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.955+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.956+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:44 volumio volumio[1131]: info: Rename folder Nov 01 23:18:44 volumio volumio[1131]: info: Folder /tmp/downloaded_plugin.zip removed Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.976+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:44 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:44.977+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:44 volumio volumio[1131]: info: Move to category Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.274+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.274+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:52 volumio volumio[1131]: info: Checking if install.sh is present Nov 01 23:18:52 volumio volumio[1131]: info: Executing install.sh Nov 01 23:18:52 volumio sudo[14027]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/install.sh Nov 01 23:18:52 volumio sudo[14027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 23:18:52 volumio sudo[14027]: pam_unix(sudo:session): session closed for user root Nov 01 23:18:52 volumio volumio[1131]: info: Install script completed Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.383+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.384+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.387+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:52 volumio volumio[1131]: info: Done installing plugin. Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.389+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:52 volumio volumio[1131]: info: Enabling plugin youtube2 Nov 01 23:18:52 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:18:52 volumio volumio[1131]: info: [{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"2.3.6","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"IR Remote Controller","name":"ir_controller","category":"system_hardware","version":"3.4.13","icon":"fa-magic","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 01 23:18:52 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 23:18:52 volumio volumio[1131]: info: YouTube2 plugin installed Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.403+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:18:52 volumio volumio5-onboarding[1345]: time=2025-11-01T23:18:52.403+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:18:52 volumio volumio[1131]: Plugin install end detected on script Nov 01 23:18:52 volumio volumio[1131]: info: Folder /tmp/plugins removed Nov 01 23:18:52 volumio volumio[1131]: info: Folder /tmp/downloaded_plugin.zip removed Nov 01 23:18:52 volumio volumio[1131]: info: Folder /data/temp removed Nov 01 23:19:07 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 23:19:10 volumio volumio[1131]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/youtube2/2.4.0/volumio/bookworm/armhf Nov 01 23:19:10 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:10.687+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:19:10 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:10.688+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:19:16 volumio volumio[1131]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/youtube2/2.4.0/volumio/bookworm/armhf Nov 01 23:19:16 volumio volumio[1131]: info: Folder /tmp/plugins removed Nov 01 23:19:16 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:16.131+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:19:16 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:16.131+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.272+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.273+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:19:18 volumio volumio[1131]: info: Check plugin dependencies Nov 01 23:19:18 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.353+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.353+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:19:18 volumio volumio[1131]: info: Checking if plugin already exists Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.358+09:00 level=DEBUG msg="received event" component=volumio/socket event=installPluginStatus Nov 01 23:19:18 volumio volumio5-onboarding[1345]: time=2025-11-01T23:19:18.358+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Nov 01 23:19:18 volumio volumio[1131]: info: An error occurred installing the plugin. Rolling back config Nov 01 23:19:18 volumio volumio[1131]: info: Plugin folders cleanup Nov 01 23:19:18 volumio volumio[1131]: info: Scanning into folder /volumio/app/plugins/ Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category audio_interface Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category miscellanea Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category music_service Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category plugins.json Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category system_controller Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category user_interface Nov 01 23:19:18 volumio volumio[1131]: info: Scanning into folder /data/plugins/ Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category music_service Nov 01 23:19:18 volumio volumio[1131]: info: Scanning category system_hardware Nov 01 23:19:18 volumio volumio[1131]: info: Plugin folders cleanup completed Nov 01 23:19:18 volumio volumio[1131]: info: Error: Error Nov 01 23:19:18 volumio volumio[1131]: info: Folder /tmp/plugins removed Nov 01 23:19:18 volumio volumio[1131]: info: Folder /tmp/downloaded_plugin.zip removed Nov 01 23:19:18 volumio volumio[1131]: info: Folder /tmp/downloaded_plugin.zip removed Nov 01 23:19:20 volumio volumio[1131]: info: Folder /data/temp removed Nov 01 23:19:23 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Nov 01 23:19:24 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:19:24 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 23:19:27 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:19:27 volumio volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:19:27 volumio volumio[1131]: TypeError: Cannot read properties of null (reading 'logger') Nov 01 23:19:27 volumio volumio[1131]: at YouTube2Context.getLogger (/data/plugins/music_service/youtube2/dist/lib/YouTube2Context.js:65:81) Nov 01 23:19:27 volumio volumio[1131]: at /data/plugins/music_service/youtube2/dist/index.js:154:39 Nov 01 23:19:27 volumio volumio[1131]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 01 23:19:27 volumio volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:19:27 volumio sudo[14120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-01 23:18' Nov 01 23:19:27 volumio sudo[14120]: 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="b5a4a000e056eaff366fbb6591bf2240b12246d0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Oct 28 13:21:04 UTC 2025" VOLUMIO_VERSION="4.063" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d4b4f47fdbfbeaa8ca800a51d0df852b"