May 25 21:09:29 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 25 21:09:29 volumio dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.209' (uid=0 pid=15793 comm="timedatectl show --property=NTPSynchronized --valu") May 25 21:09:29 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 25 21:09:30 volumio dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.timedate1' May 25 21:09:30 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 25 21:09:30 volumio setdatetime-helper.sh[15792]: Time is already synchronized. May 25 21:09:30 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 25 21:09:30 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 25 21:09:44 volumio volumio[14944]: info: CoreCommandRouter::volumioGetQueue May 25 21:09:44 volumio volumio[14944]: info: CoreStateMachine::getQueue May 25 21:09:44 volumio volumio[14944]: info: CorePlayQueue::getQueue May 25 21:09:57 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:09:57 volumio volumio[14944]: info: TuneIn handleBrowseUri: tunein/location May 25 21:09:57 volumio volumio[14944]: error: Cannot read properties of undefined (reading 'indexOf') May 25 21:09:57 volumio volumio[14944]: error: Failed to execute browseSource: Error: Cannot list category items for location: TypeError: Cannot read properties of undefined (reading 'indexOf') May 25 21:10:00 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 25 21:10:06 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:10:06 volumio volumio[14944]: info: TuneIn handleBrowseUri: tunein/location May 25 21:10:06 volumio volumio[14944]: info: Preload queue cleared May 25 21:10:09 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:10:09 volumio volumio[14944]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 May 25 21:10:09 volumio volumio[14944]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 May 25 21:10:09 volumio volumio[14944]: info: Preload queue cleared May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces system playlist update May 25 21:10:15 volumio volumio[14944]: info: Ignoring MPD Status Update May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces state update: player May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::getState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand status May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces system playlist update May 25 21:10:15 volumio volumio[14944]: info: Ignoring MPD Status Update May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces state update: player May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::getState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand status May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces system playlist update May 25 21:10:15 volumio volumio[14944]: info: Ignoring MPD Status Update May 25 21:10:15 volumio volumio[14944]: info: May 25 21:10:15 volumio volumio[14944]: ---------------------------- MPD announces state update: player May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::getState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand status May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 10ms May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand status took 9 milliseconds May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 7ms May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand status took 6 milliseconds May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 11ms May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand status took 9 milliseconds May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseState May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseTrackInfo May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseTrackInfo May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::servicePushState May 25 21:10:15 volumio volumio[14944]: info: CorePlayQueue::getTrack 0 May 25 21:10:15 volumio volumio[14944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86995,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Neil Sedaka - Oh! Carol","artist":"60s_70s","album":null,"uri":"http://strm112.1.fm/60s_70s_mobile_mp3","trackType":""} May 25 21:10:15 volumio volumio[14944]: verbose: CURRENT POSITION 0 May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState stateService play May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState currentStatus play May 25 21:10:15 volumio volumio[14944]: info: Received an update from plugin. extracting info from payload May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::servicePushState May 25 21:10:15 volumio volumio[14944]: info: CorePlayQueue::getTrack 0 May 25 21:10:15 volumio volumio[14944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86995,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Neil Sedaka - Oh! Carol","artist":"60s_70s","album":null,"uri":"http://strm112.1.fm/60s_70s_mobile_mp3","trackType":""} May 25 21:10:15 volumio volumio[14944]: verbose: CURRENT POSITION 0 May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState stateService play May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState currentStatus play May 25 21:10:15 volumio volumio[14944]: info: Received an update from plugin. extracting info from payload May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 71ms May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 69ms May 25 21:10:15 volumio volumio[14944]: info: sendMpdCommand playlistinfo took 54 milliseconds May 25 21:10:15 volumio volumio[14944]: verbose: ControllerMpd::parseTrackInfo May 25 21:10:15 volumio volumio[14944]: info: ControllerMpd::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::servicePushState May 25 21:10:15 volumio volumio[14944]: info: CorePlayQueue::getTrack 0 May 25 21:10:15 volumio volumio[14944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86995,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Neil Sedaka - Oh! Carol","artist":"60s_70s","album":null,"uri":"http://strm112.1.fm/60s_70s_mobile_mp3","trackType":""} May 25 21:10:15 volumio volumio[14944]: verbose: CURRENT POSITION 0 May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState stateService play May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::syncState currentStatus play May 25 21:10:15 volumio volumio[14944]: info: Received an update from plugin. extracting info from payload May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: CoreStateMachine::pushState May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::volumioPushState May 25 21:10:15 volumio volumio[14944]: info: ------------------------------ 89ms May 25 21:10:15 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:10:15 volumio volumio[14944]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100422 May 25 21:10:15 volumio volumio[14944]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100422 May 25 21:10:15 volumio volumio[14944]: info: Preload queue cleared May 25 21:10:15 volumio volumio[14944]: info: Preload queue cleared May 25 21:10:18 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:10:18 volumio volumio[14944]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100422&filter=s:popular May 25 21:10:18 volumio volumio[14944]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100422&filter=s:popular May 25 21:10:18 volumio volumio[14944]: info: Preload queue cleared May 25 21:10:24 volumio volumio[14944]: Searching plugin music_service/webradio May 25 21:10:24 volumio volumio[14944]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 25 21:10:24 volumio volumio[14944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:10:24 volumio volumio[14944]: TypeError: Cannot read properties of undefined (reading 'length') May 25 21:10:24 volumio volumio[14944]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 May 25 21:10:24 volumio volumio[14944]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) May 25 21:10:24 volumio volumio[14944]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 May 25 21:10:24 volumio volumio[14944]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 21:10:24 volumio volumio[14944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:10:25 volumio sudo[15995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-25 21:09' May 25 21:10:25 volumio sudo[15995]: 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"