-- Logs begin at Sun 2026-01-25 15:04:33 CET, end at Mon 2026-01-26 17:40:52 CET. -- Jan 26 17:39:30 volumio volumio[1276]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/youtube2/volumio/buster/armhf Jan 26 17:39:32 volumio volumio[1276]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/youtube2/volumio/buster/armhf Jan 26 17:39:32 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:39:33 volumio volumio[1276]: info: Check plugin dependencies Jan 26 17:39:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 17:39:33 volumio volumio[1276]: info: Checking if plugin already exists Jan 26 17:39:33 volumio volumio[1276]: info: Rename folder Jan 26 17:39:33 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:39:33 volumio volumio[1276]: info: Move to category Jan 26 17:39:35 volumio volumio[1276]: info: Checking if install.sh is present Jan 26 17:39:35 volumio volumio[1276]: info: Executing install.sh Jan 26 17:39:35 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/youtube2/install.sh Jan 26 17:39:35 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 17:39:35 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Jan 26 17:39:35 volumio volumio[1276]: info: YouTube2 plugin installed Jan 26 17:39:35 volumio volumio[1276]: Plugin install end detected on script Jan 26 17:39:35 volumio volumio[1276]: info: Install script completed Jan 26 17:39:35 volumio volumio[1276]: info: Adding reference to registry Jan 26 17:39:35 volumio volumio[1276]: info: Done installing plugin. Jan 26 17:39:35 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 17:39:35 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 26 17:39:35 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:39:35 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:39:35 volumio volumio[1276]: info: Folder /data/temp removed Jan 26 17:39:37 volumio volumio[1276]: info: Enabling plugin youtube2 Jan 26 17:39:37 volumio volumio[1276]: info: Loading plugin "youtube2"... Jan 26 17:39:39 volumio volumio[1276]: info: PLUGIN START: youtube2 Jan 26 17:39:39 volumio volumio[1276]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 17:39:39 volumio volumio[1276]: info: [1769445579495] CoreMusicLibrary::Adding element YouTube2 Jan 26 17:39:39 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 17:39:39 volumio volumio[1276]: Cannot find translation for source Randomizer Jan 26 17:39:39 volumio volumio[1276]: Cannot find translation for source TIDAL Jan 26 17:39:39 volumio volumio[1276]: Cannot find translation for source YouTube2 Jan 26 17:39:39 volumio volumio[1276]: info: Done. Jan 26 17:40:14 volumio volumio[1276]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytcr/volumio/buster/armhf Jan 26 17:40:17 volumio volumio[1276]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytcr/volumio/buster/armhf Jan 26 17:40:17 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:40:19 volumio volumio[1276]: info: Check plugin dependencies Jan 26 17:40:20 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 17:40:20 volumio volumio[1276]: info: Checking if plugin already exists Jan 26 17:40:20 volumio volumio[1276]: info: Rename folder Jan 26 17:40:20 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:40:20 volumio volumio[1276]: info: Move to category Jan 26 17:40:22 volumio volumio[1276]: info: Checking if install.sh is present Jan 26 17:40:22 volumio volumio[1276]: info: Executing install.sh Jan 26 17:40:22 volumio sudo[3106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/ytcr/install.sh Jan 26 17:40:22 volumio sudo[3106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 17:40:22 volumio sudo[3106]: pam_unix(sudo:session): session closed for user root Jan 26 17:40:22 volumio volumio[1276]: info: YouTube Cast Receiver installed Jan 26 17:40:22 volumio volumio[1276]: Plugin install end detected on script Jan 26 17:40:22 volumio volumio[1276]: info: Install script completed Jan 26 17:40:22 volumio volumio[1276]: info: Adding reference to registry Jan 26 17:40:22 volumio volumio[1276]: info: Done installing plugin. Jan 26 17:40:22 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 17:40:22 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 26 17:40:22 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:40:22 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:40:22 volumio volumio[1276]: info: Folder /data/temp removed Jan 26 17:40:24 volumio volumio[1276]: info: Enabling plugin ytcr Jan 26 17:40:24 volumio volumio[1276]: info: Loading plugin "ytcr"... Jan 26 17:40:24 volumio nmbd[865]: [2026/01/26 17:40:24.696951, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 26 17:40:24 volumio nmbd[865]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.70 for name WORKGROUP<1d>. Jan 26 17:40:24 volumio nmbd[865]: This response was from IP 192.168.0.81, reporting an IP address of 192.168.0.81. Jan 26 17:40:24 volumio nmbd[865]: [2026/01/26 17:40:24.697292, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 26 17:40:24 volumio nmbd[865]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.68 for name WORKGROUP<1d>. Jan 26 17:40:24 volumio nmbd[865]: This response was from IP 192.168.0.81, reporting an IP address of 192.168.0.81. Jan 26 17:40:26 volumio volumio[1276]: info: PLUGIN START: ytcr Jan 26 17:40:27 volumio volumio[1276]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 26 17:40:27 volumio volumio[1276]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 17:40:27 volumio volumio[1276]: info: VolumeController:: Volume=37 Mute =true Jan 26 17:40:27 volumio volumio[1276]: info: CoreCommandRouter::volumioGetState Jan 26 17:40:27 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:27 volumio volumio[1276]: info: CoreStateMachine::pushState Jan 26 17:40:27 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:27 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 17:40:27 volumio volumio[1276]: info: CoreCommandRouter::volumioPushState Jan 26 17:40:28 volumio volumio[1276]: info: MRS: Pushing multiroomSync output update for this device Jan 26 17:40:28 volumio volumio[1276]: info: MRS: Pushing multiroomSync output Jan 26 17:40:28 volumio volumio[1276]: info: CoreCommandRouter::volumioGetState Jan 26 17:40:28 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:28 volumio volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 26 17:40:28 volumio volumio[1276]: SPOTIFY: SPOTIFY VOLUME 37 Jan 26 17:40:28 volumio volumio[1276]: SPOTIFY: VOLUMIO VOLUME 0 Jan 26 17:40:28 volumio volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 26 17:40:28 volumio volumio[1276]: info: Setting Spotify Volume from Volumio: 0 Jan 26 17:40:28 volumio volumio[1276]: info: Done. Jan 26 17:40:29 volumio volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 0 Jan 26 17:40:29 volumio volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 17:40:33 volumio volumio[1276]: info: Discovery: Getting this device information Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::volumioGetState Jan 26 17:40:33 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 17:40:33 volumio volumio[1276]: verbose: New Socket.io Connection to 192.168.0.68:3000 from 192.168.0.7 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 17:40:33 volumio volumio[1276]: info: Discovery: Getting this device information Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::volumioGetState Jan 26 17:40:33 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 17:40:33 volumio volumio[1276]: verbose: New Socket.io Connection to 192.168.0.70:3000 from 192.168.0.7 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 17:40:33 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 17:40:33 volumio volumio[1276]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/buster/armhf Jan 26 17:40:35 volumio volumio[1276]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/buster/armhf Jan 26 17:40:35 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:40:36 volumio volumio[1276]: info: Check plugin dependencies Jan 26 17:40:36 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 17:40:36 volumio volumio[1276]: info: Checking if plugin already exists Jan 26 17:40:36 volumio volumio[1276]: info: Rename folder Jan 26 17:40:36 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:40:36 volumio volumio[1276]: info: Move to category Jan 26 17:40:38 volumio volumio[1276]: info: Checking if install.sh is present Jan 26 17:40:38 volumio volumio[1276]: info: Executing install.sh Jan 26 17:40:38 volumio sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/ytmusic/install.sh Jan 26 17:40:38 volumio sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 17:40:38 volumio sudo[3156]: pam_unix(sudo:session): session closed for user root Jan 26 17:40:38 volumio volumio[1276]: info: YouTube Music plugin installed Jan 26 17:40:38 volumio volumio[1276]: Plugin install end detected on script Jan 26 17:40:38 volumio volumio[1276]: info: Install script completed Jan 26 17:40:38 volumio volumio[1276]: info: Adding reference to registry Jan 26 17:40:38 volumio volumio[1276]: info: Done installing plugin. Jan 26 17:40:38 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 17:40:38 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 26 17:40:38 volumio volumio[1276]: info: Folder /tmp/plugins removed Jan 26 17:40:38 volumio volumio[1276]: info: Folder /tmp/downloaded_plugin.zip removed Jan 26 17:40:39 volumio volumio[1276]: info: Folder /data/temp removed Jan 26 17:40:42 volumio volumio[1276]: info: Enabling plugin ytmusic Jan 26 17:40:42 volumio volumio[1276]: info: Loading plugin "ytmusic"... Jan 26 17:40:43 volumio volumio[1276]: info: PLUGIN START: ytmusic Jan 26 17:40:43 volumio volumio[1276]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 17:40:43 volumio volumio[1276]: info: [1769445643774] CoreMusicLibrary::Adding element YouTube Music Jan 26 17:40:43 volumio volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 17:40:43 volumio volumio[1276]: Cannot find translation for source Randomizer Jan 26 17:40:43 volumio volumio[1276]: Cannot find translation for source TIDAL Jan 26 17:40:43 volumio volumio[1276]: Cannot find translation for source YouTube2 Jan 26 17:40:43 volumio volumio[1276]: Cannot find translation for source YouTube Music Jan 26 17:40:43 volumio volumio[1276]: info: Done. Jan 26 17:40:47 volumio volumio[1276]: info: CoreCommandRouter::volumioGetState Jan 26 17:40:47 volumio volumio[1276]: info: CorePlayQueue::getTrack 0 Jan 26 17:40:51 volumio volumio[1276]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jan 26 17:40:51 volumio volumio[1276]: info: PLUGIN onReboot : networkfs Jan 26 17:40:51 volumio volumio[1276]: info: PLUGIN onReboot : audiophonicsonoff Jan 26 17:40:51 volumio volumio[1276]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 17:40:51 volumio volumio[1276]: TypeError: Cannot read property 'writeSync' of undefined Jan 26 17:40:51 volumio volumio[1276]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Jan 26 17:40:51 volumio volumio[1276]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jan 26 17:40:51 volumio volumio[1276]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jan 26 17:40:51 volumio volumio[1276]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 26 17:40:51 volumio volumio[1276]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 26 17:40:51 volumio volumio[1276]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jan 26 17:40:51 volumio volumio[1276]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Jan 26 17:40:51 volumio volumio[1276]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Jan 26 17:40:51 volumio volumio[1276]: at Socket.emit (events.js:315:20) Jan 26 17:40:51 volumio volumio[1276]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 26 17:40:51 volumio volumio[1276]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 26 17:40:51 volumio volumio[1276]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 17:40:52 volumio sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 17:39 Jan 26 17:40:52 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:07:26 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f8c1d964af93dfdda0f02ac4140eec2"