May 28 21:20:14 volumio go-librespot[1446]: time="2025-05-28T21:20:14+02:00" level=trace msg="received accesspoint ping" May 28 21:20:14 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 28 21:20:14 volumio dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.29' (uid=0 pid=3688 comm="timedatectl show --property=NTPSynchronized --valu") May 28 21:20:14 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 28 21:20:14 volumio go-librespot[1446]: time="2025-05-28T21:20:14+02:00" level=trace msg="received accesspoint pong ack" May 28 21:20:14 volumio dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.timedate1' May 28 21:20:14 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 28 21:20:14 volumio setdatetime-helper.sh[3687]: Time is not synchronized. Attempting to sync... May 28 21:20:19 volumio go-librespot[1446]: time="2025-05-28T21:20:19+02:00" level=trace msg="sent dealer ping" May 28 21:20:19 volumio go-librespot[1446]: time="2025-05-28T21:20:19+02:00" level=trace msg="received dealer pong" May 28 21:20:19 volumio sudo[3721]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 28 May 2025 19:20:19 GMT#015' May 28 21:20:19 volumio sudo[3721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 21:20:19 volumio setdatetime-helper.sh[3722]: Wed May 28 21:20:19 CEST 2025 May 28 21:20:19 volumio systemd-journald[476]: Time jumped backwards, rotating. May 28 21:20:19 volumio sudo[3721]: pam_unix(sudo:session): session closed for user root May 28 21:20:19 volumio setdatetime-helper.sh[3687]: Time synchronized successfully. May 28 21:20:19 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 28 21:20:19 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 28 21:20:43 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 28 21:20:44 volumio volumio[886]: info: CorePlayQueue::getTrack 0 May 28 21:20:44 volumio volumio[886]: info: CorePlayQueue::getTrack 1 May 28 21:20:48 volumio go-librespot[1446]: time="2025-05-28T21:20:48+02:00" level=trace msg="sent dealer ping" May 28 21:20:48 volumio go-librespot[1446]: time="2025-05-28T21:20:48+02:00" level=trace msg="received dealer pong" May 28 21:20:49 volumio volumio[886]: info: May 28 21:20:49 volumio volumio[886]: ---------------------------- MPD announces state update: player May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::getState May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 28 21:20:49 volumio volumio[886]: info: May 28 21:20:49 volumio volumio[886]: ---------------------------- MPD announces state update: player May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::getState May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 28 21:20:49 volumio volumio[886]: info: May 28 21:20:49 volumio volumio[886]: ---------------------------- MPD announces state update: player May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::getState May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 28 21:20:49 volumio volumio[886]: info: sendMpdCommand status took 1 milliseconds May 28 21:20:49 volumio volumio[886]: info: sendMpdCommand status took 0 milliseconds May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::parseState May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::parseState May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::servicePushState May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: CorePlayQueue::getTrack 0 May 28 21:20:49 volumio volumio[886]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 21:20:49 volumio volumio[886]: verbose: CURRENT POSITION 0 May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState stateService stop May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus play May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::stPlaybackTimer May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::servicePushState May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: CorePlayQueue::getTrack 0 May 28 21:20:49 volumio volumio[886]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 21:20:49 volumio volumio[886]: verbose: CURRENT POSITION 0 May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState stateService stop May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus stop May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: No code May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: ------------------------------ 11ms May 28 21:20:49 volumio volumio[886]: info: ------------------------------ 11ms May 28 21:20:49 volumio volumio[886]: info: sendMpdCommand status took 11 milliseconds May 28 21:20:49 volumio volumio[886]: verbose: ControllerMpd::parseState May 28 21:20:49 volumio volumio[886]: info: ControllerMpd::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::servicePushState May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: CorePlayQueue::getTrack 0 May 28 21:20:49 volumio volumio[886]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 21:20:49 volumio volumio[886]: verbose: CURRENT POSITION 0 May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState stateService stop May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus stop May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: No code May 28 21:20:49 volumio volumio[886]: info: CoreStateMachine::pushState May 28 21:20:49 volumio volumio[886]: info: CoreCommandRouter::volumioPushState May 28 21:20:49 volumio volumio[886]: info: ------------------------------ 16ms May 28 21:20:55 volumio volumio[886]: info: CorePlayQueue::getTrack 0 May 28 21:20:55 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: youtube2 , goto May 28 21:21:08 volumio volumio[886]: Searching all installed plugins May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: , search May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 28 21:21:08 volumio volumio[886]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 28 21:21:08 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 28 21:21:08 volumio volumio[886]: Use XMLElement.setAttribute instead May 28 21:21:08 volumio volumio[886]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:21:08 volumio volumio[886]: TypeError: children[i].attr(...).value is not a function May 28 21:21:08 volumio volumio[886]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 28 21:21:08 volumio volumio[886]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 28 21:21:08 volumio volumio[886]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:21:08 volumio sudo[3875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-28 21:20' May 28 21:21:08 volumio sudo[3875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 07:33:33 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="d67a2da4ffe3f001e986b1f31eaa3a76"