Jun 13 12:52:00 volumio systemd[1]: Starting upower.service - Daemon for power management... Jun 13 12:52:00 volumio dbus-daemon[583]: [system] Successfully activated service 'org.freedesktop.UPower' Jun 13 12:52:00 volumio systemd[1]: Started upower.service - Daemon for power management. Jun 13 12:52:00 volumio volumio[2865]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jun 13 12:52:03 volumio volumio[2865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 13 12:52:03 volumio volumio[2865]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jun 13 12:52:03 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:04 volumio nmbd[946]: [2025/06/13 12:52:04.025707, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 13 12:52:04 volumio nmbd[946]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 13 12:52:05 volumio volumio[2865]: info: Check plugin dependencies Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:52:05 volumio volumio[2865]: info: Checking if plugin already exists Jun 13 12:52:05 volumio volumio[2865]: info: Rename folder Jun 13 12:52:05 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:52:05 volumio volumio[2865]: info: Move to category Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 12:52:05 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:05 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:05 volumio volumio[2865]: info: Listing playlists Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:05 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:05 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:05 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 13 12:52:08 volumio volumio[2865]: info: Checking if install.sh is present Jun 13 12:52:08 volumio volumio[2865]: info: Executing install.sh Jun 13 12:52:08 volumio volumio[2865]: Upnp client error: Error: This socket has been ended by the other party Jun 13 12:52:08 volumio sudo[8676]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Jun 13 12:52:08 volumio sudo[8676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:52:08 volumio volumio[2865]: info: Installing Go-librespot Jun 13 12:52:08 volumio volumio[2865]: info: Checking old vollibrespot installs Jun 13 12:52:08 volumio systemd[1]: Reloading. Jun 13 12:52:09 volumio volumio[2865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 13 12:52:10 volumio volumio[2865]: info: Downloading daemon Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 12:52:10 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:10 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:10 volumio volumio[2865]: info: Listing playlists Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:10 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:10 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:10 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 13 12:52:11 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 13 12:52:13 volumio volumio[2865]: info: Creating directories Jun 13 12:52:13 volumio volumio[2865]: info: Creating data directory Jun 13 12:52:14 volumio volumio[2865]: info: Creating Start Script Jun 13 12:52:14 volumio systemd[1]: Reloading. Jun 13 12:52:14 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:14 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:14 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:14 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:14 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:14 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:14 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:15 volumio sudo[8676]: pam_unix(sudo:session): session closed for user root Jun 13 12:52:15 volumio volumio[2865]: info: Install script completed Jun 13 12:52:15 volumio volumio[2865]: info: Adding reference to registry Jun 13 12:52:15 volumio volumio[2865]: info: Done installing plugin. Jun 13 12:52:15 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:15 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 13 12:52:15 volumio volumio[2865]: Plugin install end detected on script Jun 13 12:52:15 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:15 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:15 volumio volumio[2865]: info: Folder /data/temp removed Jun 13 12:52:15 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... Jun 13 12:52:18 volumio systemd[1]: apt-daily.service: Deactivated successfully. Jun 13 12:52:18 volumio systemd[1]: Finished apt-daily.service - Daily apt download activities. Jun 13 12:52:18 volumio systemd[1]: apt-daily.service: Consumed 1.320s CPU time. Jun 13 12:52:18 volumio volumio[2865]: info: Enabling plugin spop Jun 13 12:52:18 volumio volumio[2865]: info: Loading plugin "spop"... Jun 13 12:52:20 volumio volumio[2865]: info: PLUGIN START: spop Jun 13 12:52:20 volumio volumio[2865]: info: Creating Spotify config file Jun 13 12:52:20 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:52:20 volumio volumio[2865]: info: Done. Jun 13 12:52:20 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 13 12:52:20 volumio volumio[2865]: info: Spotify config file written Jun 13 12:52:20 volumio volumio[2865]: info: No need to fix Spotify hosts Jun 13 12:52:21 volumio sudo[8837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 13 12:52:21 volumio sudo[8837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:52:21 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 13 12:52:21 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 13 12:52:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 13 12:52:21 volumio sudo[8837]: pam_unix(sudo:session): session closed for user root Jun 13 12:52:21 volumio go-librespot[8839]: go-librespot daemon starting... Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=info msg="running go-librespot 0.2.0" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=debug msg="no app state found" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=debug msg="stored credentials not found" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=info msg="generated new device id: 60f7130e5fc0d015be88a07dbcdff79b25b6bd71" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 13 12:52:21 volumio go-librespot[8840]: time="2025-06-13T12:52:21+07:00" level=info msg="zeroconf server listening on port 43519" Jun 13 12:52:22 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 13 12:52:24 volumio volumio[2865]: info: go-librespot daemon successfully initialized Jun 13 12:52:27 volumio volumio[2865]: info: Initializing connection to go-librespot Websocket Jun 13 12:52:27 volumio go-librespot[8840]: time="2025-06-13T12:52:27+07:00" level=debug msg="new websocket client" Jun 13 12:52:27 volumio volumio[2865]: info: Connection to go-librespot Websocket established Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:52:28 volumio volumio[2865]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 13 12:52:28 volumio volumio[2865]: info: Received Get System Version Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:52:28 volumio volumio[2865]: info: Received Get System Info Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:28 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:28 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:30 volumio volumio[2865]: info: Getting Spotify volume Jun 13 12:52:30 volumio volumio[2865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 13 12:52:30 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:30 volumio volumio[2865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 13 12:52:30 volumio volumio[2865]: SPOTIFY: SPOTIFY VOLUME undefined Jun 13 12:52:30 volumio volumio[2865]: SPOTIFY: VOLUMIO VOLUME 86 Jun 13 12:52:30 volumio volumio[2865]: info: Aligning Spotify Volume to Volumio Volume Jun 13 12:52:30 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:30 volumio volumio[2865]: info: Setting Spotify Volume from Volumio: 86 Jun 13 12:52:30 volumio volumio[2865]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Jun 13 12:52:31 volumio volumio[2865]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jun 13 12:52:31 volumio volumio[2865]: info: Sending Spotify command with payload to local API: /player/volume Jun 13 12:52:33 volumio volumio[2865]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Jun 13 12:52:33 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:34 volumio volumio[2865]: info: Check plugin dependencies Jun 13 12:52:35 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:52:35 volumio volumio[2865]: info: Checking if plugin already exists Jun 13 12:52:35 volumio volumio[2865]: info: Rename folder Jun 13 12:52:35 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:35 volumio volumio[2865]: info: Move to category Jun 13 12:52:39 volumio volumio[2865]: info: Checking if install.sh is present Jun 13 12:52:39 volumio volumio[2865]: info: Executing install.sh Jun 13 12:52:39 volumio volumio[2865]: Upnp client error: Error: This socket has been ended by the other party Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:52:39 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:52:39 volumio sudo[8923]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/install.sh Jun 13 12:52:39 volumio sudo[8923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:52:39 volumio sudo[8923]: pam_unix(sudo:session): session closed for user root Jun 13 12:52:39 volumio volumio[2865]: info: YouTube2 plugin installed Jun 13 12:52:39 volumio volumio[2865]: Plugin install end detected on script Jun 13 12:52:39 volumio volumio[2865]: info: Install script completed Jun 13 12:52:39 volumio volumio[2865]: info: Adding reference to registry Jun 13 12:52:39 volumio volumio[2865]: info: Done installing plugin. Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:39 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 13 12:52:39 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:39 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:39 volumio volumio[2865]: info: Folder /data/temp removed Jun 13 12:52:42 volumio volumio[2865]: info: Enabling plugin youtube2 Jun 13 12:52:42 volumio volumio[2865]: info: Loading plugin "youtube2"... Jun 13 12:52:44 volumio volumio[2865]: info: PLUGIN START: youtube2 Jun 13 12:52:44 volumio volumio[2865]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 13 12:52:44 volumio volumio[2865]: info: [1749793964474] CoreMusicLibrary::Adding element YouTube2 Jun 13 12:52:44 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:52:44 volumio volumio[2865]: Cannot find translation for source YouTube2 Jun 13 12:52:44 volumio volumio[2865]: info: Done. Jun 13 12:52:51 volumio volumio[2865]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Jun 13 12:52:53 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 13 12:52:53 volumio dbus-daemon[583]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.91' (uid=0 pid=8968 comm="timedatectl show --property=NTPSynchronized --valu") Jun 13 12:52:53 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 13 12:52:53 volumio dbus-daemon[583]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 13 12:52:53 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 13 12:52:53 volumio setdatetime-helper.sh[8967]: Time is not synchronized. Attempting to sync... Jun 13 12:52:53 volumio sudo[8978]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 13 Jun 2025 05:52:54 GMT#015' Jun 13 12:52:53 volumio sudo[8978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:52:54 volumio setdatetime-helper.sh[8979]: Fri Jun 13 12:52:54 +07 2025 Jun 13 12:52:54 volumio sudo[8978]: pam_unix(sudo:session): session closed for user root Jun 13 12:52:54 volumio setdatetime-helper.sh[8967]: Time synchronized successfully. Jun 13 12:52:54 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 13 12:52:54 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 13 12:52:54 volumio volumio[2865]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Jun 13 12:52:54 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:56 volumio volumio[2865]: info: Check plugin dependencies Jun 13 12:52:56 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:52:56 volumio volumio[2865]: info: Checking if plugin already exists Jun 13 12:52:56 volumio volumio[2865]: info: Rename folder Jun 13 12:52:56 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:56 volumio volumio[2865]: info: Move to category Jun 13 12:52:58 volumio volumio[2865]: info: Checking if install.sh is present Jun 13 12:52:58 volumio volumio[2865]: info: Executing install.sh Jun 13 12:52:58 volumio sudo[9014]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh Jun 13 12:52:58 volumio sudo[9014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:52:58 volumio sudo[9014]: pam_unix(sudo:session): session closed for user root Jun 13 12:52:58 volumio volumio[2865]: info: YouTube Music plugin installed Jun 13 12:52:58 volumio volumio[2865]: Plugin install end detected on script Jun 13 12:52:58 volumio volumio[2865]: info: Install script completed Jun 13 12:52:58 volumio volumio[2865]: info: Adding reference to registry Jun 13 12:52:58 volumio volumio[2865]: info: Done installing plugin. Jun 13 12:52:58 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:52:58 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 13 12:52:58 volumio volumio[2865]: info: Folder /tmp/plugins removed Jun 13 12:52:58 volumio volumio[2865]: info: Folder /tmp/downloaded_plugin.zip removed Jun 13 12:52:58 volumio volumio[2865]: info: Folder /data/temp removed Jun 13 12:53:01 volumio volumio[2865]: info: Enabling plugin ytmusic Jun 13 12:53:01 volumio volumio[2865]: info: Loading plugin "ytmusic"... Jun 13 12:53:03 volumio volumio[2865]: info: PLUGIN START: ytmusic Jun 13 12:53:03 volumio volumio[2865]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 13 12:53:03 volumio volumio[2865]: info: [1749793983004] CoreMusicLibrary::Adding element YouTube Music Jun 13 12:53:03 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:53:03 volumio volumio[2865]: Cannot find translation for source YouTube2 Jun 13 12:53:03 volumio volumio[2865]: Cannot find translation for source YouTube Music Jun 13 12:53:03 volumio volumio[2865]: info: Done. Jun 13 12:53:05 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:53:08 volumio nmbd[946]: [2025/06/13 12:53:08.910850, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 13 12:53:08 volumio nmbd[946]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 13 12:53:16 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 12:53:16 volumio volumio[2865]: info: Preload queue cleared Jun 13 12:53:18 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:53:18 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:53:18 volumio volumio[2865]: info: Discovery: Getting this device information Jun 13 12:53:18 volumio volumio[2865]: info: CoreCommandRouter::volumioGetState Jun 13 12:53:18 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:53:18 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 12:53:18 volumio volumio[2865]: info: Preload queue cleared Jun 13 12:53:23 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 13 12:53:29 volumio volumio[2865]: Searching plugin music_service/webradio Jun 13 12:53:29 volumio volumio[2865]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 13 12:53:30 volumio volumio[2865]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 12:53:30 volumio volumio[2865]: TypeError: Cannot read properties of undefined (reading 'length') Jun 13 12:53:30 volumio volumio[2865]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 13 12:53:30 volumio volumio[2865]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 13 12:53:30 volumio volumio[2865]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 13 12:53:30 volumio volumio[2865]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 13 12:53:30 volumio volumio[2865]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 12:53:31 volumio sudo[9209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-13 12:52' Jun 13 12:53:31 volumio sudo[9209]: 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"