Aug 29 19:32:13 work-volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 29 19:32:13 work-volumio dbus-daemon[577]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38835' (uid=0 pid=31138 comm="timedatectl show --property=NTPSynchronized --valu") Aug 29 19:32:13 work-volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 29 19:32:14 work-volumio dbus-daemon[577]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 29 19:32:14 work-volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 29 19:32:14 work-volumio setdatetime-helper.sh[31137]: Time is already synchronized. Aug 29 19:32:14 work-volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 29 19:32:14 work-volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 29 19:32:44 work-volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces system playlist update Aug 29 19:33:31 work-volumio volumio[22426]: info: Ignoring MPD Status Update Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces state update: player Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::getState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand status Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces system playlist update Aug 29 19:33:31 work-volumio volumio[22426]: info: Ignoring MPD Status Update Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces state update: player Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::getState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand status Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces system playlist update Aug 29 19:33:31 work-volumio volumio[22426]: info: Ignoring MPD Status Update Aug 29 19:33:31 work-volumio volumio[22426]: info: Aug 29 19:33:31 work-volumio volumio[22426]: ---------------------------- MPD announces state update: player Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::getState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand status Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 4ms Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand status took 4 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 4ms Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand status took 4 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 3ms Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand status took 2 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseState Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseTrackInfo Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseTrackInfo Aug 29 19:33:31 work-volumio volumio[22426]: verbose: ControllerMpd::parseTrackInfo Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::servicePushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CorePlayQueue::getTrack 0 Aug 29 19:33:31 work-volumio volumio[22426]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8916356,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 19:33:31 work-volumio volumio[22426]: verbose: CURRENT POSITION 0 Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState stateService play Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState currentStatus play Aug 29 19:33:31 work-volumio volumio[22426]: info: Received an update from plugin. extracting info from payload Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::servicePushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CorePlayQueue::getTrack 0 Aug 29 19:33:31 work-volumio volumio[22426]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8916356,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 19:33:31 work-volumio volumio[22426]: verbose: CURRENT POSITION 0 Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState stateService play Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState currentStatus play Aug 29 19:33:31 work-volumio volumio[22426]: info: Received an update from plugin. extracting info from payload Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: ControllerMpd::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::servicePushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CorePlayQueue::getTrack 0 Aug 29 19:33:31 work-volumio volumio[22426]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8916356,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Aug 29 19:33:31 work-volumio volumio[22426]: verbose: CURRENT POSITION 0 Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState stateService play Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::syncState currentStatus play Aug 29 19:33:31 work-volumio volumio[22426]: info: Received an update from plugin. extracting info from payload Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreStateMachine::pushState Aug 29 19:33:31 work-volumio volumio[22426]: info: CoreCommandRouter::volumioPushState Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 25ms Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 26ms Aug 29 19:33:31 work-volumio volumio[22426]: info: ------------------------------ 26ms Aug 29 19:33:31 work-volumio volumio[22426]: info: [LastFM] Split composite title into artist: Radio Swiss Jazz and title: www.radioswissjazz.ch Aug 29 19:33:32 work-volumio volumio[22426]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:33:32 work-volumio volumio[22426]: TypeError: Cannot read properties of null (reading '@') Aug 29 19:33:32 work-volumio volumio[22426]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Aug 29 19:33:32 work-volumio volumio[22426]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Aug 29 19:33:32 work-volumio volumio[22426]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:318:18) Aug 29 19:33:32 work-volumio volumio[22426]: at Parser.emit (node:events:514:28) Aug 29 19:33:32 work-volumio volumio[22426]: at exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:328:16) Aug 29 19:33:32 work-volumio volumio[22426]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Aug 29 19:33:32 work-volumio volumio[22426]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Aug 29 19:33:32 work-volumio volumio[22426]: at IncomingMessage.emit (node:events:526:35) Aug 29 19:33:32 work-volumio volumio[22426]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 29 19:33:32 work-volumio volumio[22426]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Aug 29 19:33:32 work-volumio volumio[22426]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:33:33 work-volumio sudo[31287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-29 19:32' Aug 29 19:33:33 work-volumio sudo[31287]: 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="477d19cd2b4ebf4b4c802be78e00bbb657e83aea" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 2 17:34:53 UTC 2025" VOLUMIO_VERSION="4.021" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6199706cbb86ad1289fc7190b8a00601"