Sep 14 13:01:06 myvolumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 13:01:06 myvolumio dbus-daemon[640]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.28' (uid=0 pid=2045 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 13:01:06 myvolumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 13:01:06 myvolumio dbus-daemon[640]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 13:01:06 myvolumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 13:01:06 myvolumio setdatetime-helper.sh[2044]: Time is not synchronized. Attempting to sync... Sep 14 13:01:06 myvolumio sudo[2053]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 14 Sep 2025 13:01:07 GMT#015' Sep 14 13:01:06 myvolumio sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 13:01:07 myvolumio setdatetime-helper.sh[2054]: Sun Sep 14 13:01:07 UTC 2025 Sep 14 13:01:07 myvolumio sudo[2053]: pam_unix(sudo:session): session closed for user root Sep 14 13:01:07 myvolumio setdatetime-helper.sh[2044]: Time synchronized successfully. Sep 14 13:01:07 myvolumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 13:01:07 myvolumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 13:01:36 myvolumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 14 13:02:40 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8:3000 from 192.168.2.18 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Sep 14 13:02:42 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8 from 192.168.2.18 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 14 13:02:42 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8 from 192.168.2.18 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 14 13:02:42 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8 from 192.168.2.18 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 14 13:02:42 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8 from 192.168.2.18 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 14 13:02:44 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8 from 192.168.2.18 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreCommandRouter::volumioGetState Sep 14 13:02:44 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 13:02:44 myvolumio volumio[1034]: info: Listing playlists Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreStateMachine::getQueue Sep 14 13:02:44 myvolumio volumio[1034]: info: CorePlayQueue::getQueue Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 13:02:44 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreCommandRouter::volumioSeek Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::seek Sep 14 13:02:50 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:50 myvolumio volumio[1034]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Praise%20(feat.%20Brandon%20Lake%2C%20Chris%20Brown%20%26%20Chandler%20Moore)%20%7C%20Elevation%20Worship%22%2C%22artist%22%3A%22Elevation%20Worship%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Ff2oxGYpuLkw%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCkZKQrEp-iOPGl08hbfJ0GD73d4g%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D%7D","albumart":"https://i.ytimg.com/vi/f2oxGYpuLkw/hqdefault.jpg?sqp=-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg==&rs=AOn4CLCkZKQrEp-iOPGl08hbfJ0GD73d4g","artist":"Elevation Worship","album":"YouTube","name":"Praise (feat. Brandon Lake, Chris Brown & Chandler Moore) | Elevation Worship","title":"Praise (feat. Brandon Lake, Chris Brown & Chandler Moore) | Elevation Worship"} Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:02:50 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 13:02:50 myvolumio volumio[1034]: info: ControllerMpd::seek Sep 14 13:02:50 myvolumio mpd[1301]: exception: Bad song index Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::pushState Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreCommandRouter::volumioPushState Sep 14 13:02:50 myvolumio volumio[1034]: error: [2@0] {seek} Bad song index Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreCommandRouter::volumioSeek Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::seek Sep 14 13:02:50 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:50 myvolumio volumio[1034]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Praise%20(feat.%20Brandon%20Lake%2C%20Chris%20Brown%20%26%20Chandler%20Moore)%20%7C%20Elevation%20Worship%22%2C%22artist%22%3A%22Elevation%20Worship%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Ff2oxGYpuLkw%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCkZKQrEp-iOPGl08hbfJ0GD73d4g%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D%7D","albumart":"https://i.ytimg.com/vi/f2oxGYpuLkw/hqdefault.jpg?sqp=-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg==&rs=AOn4CLCkZKQrEp-iOPGl08hbfJ0GD73d4g","artist":"Elevation Worship","album":"YouTube","name":"Praise (feat. Brandon Lake, Chris Brown & Chandler Moore) | Elevation Worship","title":"Praise (feat. Brandon Lake, Chris Brown & Chandler Moore) | Elevation Worship"} Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:02:50 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 13:02:50 myvolumio volumio[1034]: info: ControllerMpd::seek Sep 14 13:02:50 myvolumio mpd[1301]: exception: Bad song index Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreStateMachine::pushState Sep 14 13:02:50 myvolumio volumio[1034]: info: CoreCommandRouter::volumioPushState Sep 14 13:02:50 myvolumio volumio[1034]: error: [2@0] {seek} Bad song index Sep 14 13:02:51 myvolumio volumio[1034]: info: CoreCommandRouter::volumioPlay Sep 14 13:02:51 myvolumio volumio[1034]: info: CoreStateMachine::play index undefined Sep 14 13:02:51 myvolumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:02:51 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:51 myvolumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:02:51 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:51 myvolumio volumio[1034]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Praise%20(feat.%20Brandon%20Lake%2C%20Chris%20Brown%20%26%20Chandler%20Moore)%20%7C%20Elevation%20Worship%22%2C%22artist%22%3A%22Elevation%20Worship%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Ff2oxGYpuLkw%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCkZKQrEp-iOPGl08hbfJ0GD73d4g%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22f2oxGYpuLkw%22%2C%22playlistId%22%3A%22PLIdGxYqxOZEXyzTG_WXp9cujA3_xgp5Ps%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D%7D Sep 14 13:02:52 myvolumio volumio[1034]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(f2oxGYpuLkw): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Sep 14 13:02:52 myvolumio volumio[1034]: error: [youtube2] Error in VideoModel.getInfo(f2oxGYpuLkw): y[...] is not a function Error: y[...] is not a function Sep 14 13:02:52 myvolumio volumio[1034]: at CallExpression._CallExpression_throwError (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:397:11) Sep 14 13:02:52 myvolumio volumio[1034]: at CallExpression.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:379:98) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at AssignmentExpression.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:222:37) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at ExpressionStatement.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:462:25) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at BlockStatement.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:298:35) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at descramble_sig (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:545:27) Sep 14 13:02:52 myvolumio volumio[1034]: at CallExpression.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:390:12) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at ExpressionStatement.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:462:25) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.visitNode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1018:23) Sep 14 13:02:52 myvolumio volumio[1034]: at Visitor.run (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:1008:21) Sep 14 13:02:52 myvolumio volumio[1034]: at Jinter.evaluate (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:6253:25) Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 13:02:54 myvolumio volumio[1034]: info: Discovery: Getting this device information Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::volumioGetState Sep 14 13:02:54 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 13:02:54 myvolumio volumio[1034]: verbose: New Socket.io Connection to 192.168.2.8:3000 from 192.168.2.18 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::volumioGetState Sep 14 13:02:54 myvolumio volumio[1034]: info: CorePlayQueue::getTrack 0 Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 13:02:54 myvolumio ntpd[909]: CLOCK: time stepped by 0.212302 Sep 14 13:02:54 myvolumio ntpd[909]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Sep 14 13:02:54 myvolumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 13:02:54 myvolumio dbus-daemon[640]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=2219 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 13:02:54 myvolumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 13:02:54 myvolumio dbus-daemon[640]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 13:02:54 myvolumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 13:02:54 myvolumio setdatetime-helper.sh[2218]: Time is not synchronized. Attempting to sync... Sep 14 13:02:54 myvolumio sudo[2227]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 14 Sep 2025 13:02:54 GMT#015' Sep 14 13:02:54 myvolumio sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 13:02:54 myvolumio setdatetime-helper.sh[2228]: Sun Sep 14 13:02:54 UTC 2025 Sep 14 13:02:54 myvolumio setdatetime-helper.sh[2218]: Time synchronized successfully. Sep 14 13:02:54 myvolumio sudo[2227]: pam_unix(sudo:session): session closed for user root Sep 14 13:02:54 myvolumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 13:02:54 myvolumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 13:02:54 myvolumio systemd-journald[342]: Time jumped backwards, rotating. Sep 14 13:02:54 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:02:55 myvolumio volumio[1034]: info: Preload queue cleared Sep 14 13:02:59 myvolumio volumio[1034]: Searching plugin music_service/webradio Sep 14 13:02:59 myvolumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 14 13:02:59 myvolumio volumio[1034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 13:02:59 myvolumio volumio[1034]: TypeError: Cannot read properties of undefined (reading 'length') Sep 14 13:02:59 myvolumio volumio[1034]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Sep 14 13:02:59 myvolumio volumio[1034]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Sep 14 13:02:59 myvolumio volumio[1034]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Sep 14 13:02:59 myvolumio volumio[1034]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 14 13:02:59 myvolumio volumio[1034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 13:02:59 myvolumio sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 13:01' Sep 14 13:02:59 myvolumio sudo[2260]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"