Sep 01 11:07:20 volumio go-librespot[26679]: time="2025-09-01T11:07:20+02:00" level=trace msg="sent dealer ping" Sep 01 11:07:21 volumio go-librespot[26679]: time="2025-09-01T11:07:21+02:00" level=trace msg="received dealer pong" Sep 01 11:07:48 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 11:07:48 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 11:07:48 volumio volumio[26450]: info: Discovery: Getting this device information Sep 01 11:07:48 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:07:48 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:07:48 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 11:07:48 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 01 11:07:48 volumio dbus-daemon[642]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.3597' (uid=0 pid=4205 comm="timedatectl show --property=NTPSynchronized --valu") Sep 01 11:07:48 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 01 11:07:48 volumio dbus-daemon[642]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 11:07:48 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 01 11:07:48 volumio setdatetime-helper.sh[4204]: Time is already synchronized. Sep 01 11:07:48 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 01 11:07:48 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 01 11:07:50 volumio go-librespot[26679]: time="2025-09-01T11:07:50+02:00" level=trace msg="sent dealer ping" Sep 01 11:07:51 volumio go-librespot[26679]: time="2025-09-01T11:07:51+02:00" level=trace msg="received dealer pong" Sep 01 11:08:03 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 11:08:03 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 11:08:03 volumio volumio[26450]: info: Discovery: Getting this device information Sep 01 11:08:03 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:08:03 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:08:03 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 11:08:09 volumio volumio[26450]: verbose: New Socket.io Connection to 192.168.100.51:3000 from 192.168.100.48 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 01 11:08:10 volumio volumio[26450]: verbose: New Socket.io Connection to 192.168.100.51:3000 from 192.168.100.48 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Sep 01 11:08:18 volumio volumio[26450]: verbose: New Socket.io Connection to 192.168.100.51 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:08:18 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::volumioGetQueue Sep 01 11:08:18 volumio volumio[26450]: info: CoreStateMachine::getQueue Sep 01 11:08:18 volumio volumio[26450]: info: CorePlayQueue::getQueue Sep 01 11:08:18 volumio volumio[26450]: info: Listing playlists Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 11:08:18 volumio volumio[26450]: info: Received Get System Info Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 11:08:18 volumio volumio[26450]: info: Discovery: Getting this device information Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:08:18 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:08:18 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:08:18 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 11:08:18 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 01 11:08:19 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 11:08:19 volumio volumio[26450]: info: Received Get System Info Sep 01 11:08:19 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 11:08:19 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 11:08:19 volumio volumio[26450]: info: Discovery: Getting this device information Sep 01 11:08:19 volumio volumio[26450]: info: CoreCommandRouter::volumioGetState Sep 01 11:08:19 volumio volumio[26450]: info: CorePlayQueue::getTrack 0 Sep 01 11:08:19 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 11:08:20 volumio go-librespot[26679]: time="2025-09-01T11:08:20+02:00" level=trace msg="sent dealer ping" Sep 01 11:08:21 volumio go-librespot[26679]: time="2025-09-01T11:08:21+02:00" level=trace msg="received dealer pong" Sep 01 11:08:30 volumio volumio[26450]: Searching all installed plugins Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: , search Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 01 11:08:30 volumio volumio[26450]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Sep 01 11:08:30 volumio volumio[26450]: info: [youtube2] InnertubeLoader: creating Innertube instance... Sep 01 11:08:30 volumio volumio[26450]: info: CoreCommandRouter::executeOnPlugin: spop , search Sep 01 11:08:30 volumio volumio[26450]: info: New access token = BQBqOm0f7sSikD44N3V4SbJb15boPe9-qHxuj75qWT72sXWL6o2YcvglOV-YgbV2ISfuyeySDcpp_Z_Xcp0mLTDVXGBU_UTXI5DOy5nLJsHtfxCpLaA-Uqr-5YEnsBqOZumny0WG5glmN4xCw6yZnsnig8yl95tnHWj9TYrl3t5X-RkgXwuzPACxOnbZvPqqMpnuMdsgdCoJ-kx3AOUV5gUEB2zg9hV74RGoLs8MO1Yw1l6FXj82RHHG6y_7HY0X8As1GwV-HR9LHmtk7YT3H06aQES83PiVcD82C_uUFqwidyVgIHo Sep 01 11:08:30 volumio volumio[26450]: Use XMLElement.setAttribute instead Sep 01 11:08:30 volumio volumio[26450]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 11:08:30 volumio volumio[26450]: TypeError: children[i].attr(...).value is not a function Sep 01 11:08:30 volumio volumio[26450]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Sep 01 11:08:30 volumio volumio[26450]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 01 11:08:30 volumio volumio[26450]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 11:08:31 volumio sudo[4284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-01 11:07' Sep 01 11:08:31 volumio sudo[4284]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" 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 May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"