Jun 12 19:45:14 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 19:45:14 volumio dbus-daemon[933]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1626' (uid=0 pid=20594 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 19:45:14 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 19:45:14 volumio dbus-daemon[933]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 19:45:14 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 19:45:14 volumio setdatetime-helper.sh[20593]: Time is already synchronized. Jun 12 19:45:14 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 19:45:14 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 19:45:44 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 12 19:45:59 volumio volumio[10561]: info: CALLMETHOD: music_service minidlna saveConf [object Object] Jun 12 19:45:59 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: minidlna , saveConf Jun 12 19:45:59 volumio volumio[10561]: info: minidlna: /data/minidlna.conf written Jun 12 19:45:59 volumio volumio[10561]: info: minidlna: Restarting minidlna.service Jun 12 19:45:59 volumio sudo[20660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart minidlna.service Jun 12 19:45:59 volumio sudo[20660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:45:59 volumio systemd[1]: Stopping minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server... Jun 12 19:45:59 volumio systemd[1]: minidlna.service: Deactivated successfully. Jun 12 19:45:59 volumio systemd[1]: Stopped minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Jun 12 19:45:59 volumio systemd[1]: minidlna.service: Consumed 8min 55.959s CPU time. Jun 12 19:45:59 volumio systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Jun 12 19:45:59 volumio sudo[20660]: pam_unix(sudo:session): session closed for user root Jun 12 19:45:59 volumio volumio[10561]: info: minidlna: systemctl restart minidlna.service succeeded. Jun 12 19:46:03 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 19:46:03 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 12 19:46:07 volumio volumio[10561]: info: CoreCommandRouter::volumioGetState Jun 12 19:46:07 volumio volumio[10561]: info: CorePlayQueue::getTrack 18 Jun 12 19:46:20 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:20 volumio volumio[10561]: info: Preload queue cleared Jun 12 19:46:25 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:25 volumio volumio[10561]: info: Preload queue cleared Jun 12 19:46:27 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:27 volumio volumio[10561]: info: Preload queue cleared Jun 12 19:46:27 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:28 volumio volumio[10561]: info: Preload queue cleared Jun 12 19:46:28 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$0 Jun 12 19:46:28 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$0 in service upnp_browser Jun 12 19:46:31 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:31 volumio volumio[10561]: info: Preload queue cleared Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$2 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$3 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$4 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$5 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$7 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$8 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$9 Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$A Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$B Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$C Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$D Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$E Jun 12 19:46:31 volumio volumio[10561]: info: Preloading song: upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$F Jun 12 19:46:31 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$2 in service upnp_browser Jun 12 19:46:31 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$3 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$4 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$5 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$7 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$8 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$9 in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$A in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$B in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$C in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$D in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$E in service upnp_browser Jun 12 19:46:32 volumio volumio[10561]: info: Exploding uri upnp/http://192.168.2.154:8200/ctl/ContentDir@1$14$0$0$2$F in service upnp_browser Jun 12 19:46:41 volumio volumio[10561]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 19:46:41 volumio volumio[10561]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:46:41 volumio volumio[10561]: TypeError: Cannot read properties of undefined (reading 'length') Jun 12 19:46:41 volumio volumio[10561]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 12 19:46:41 volumio volumio[10561]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 12 19:46:41 volumio volumio[10561]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 12 19:46:41 volumio volumio[10561]: at Parser.emit (node:events:514:28) Jun 12 19:46:41 volumio volumio[10561]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 12 19:46:41 volumio volumio[10561]: at emit (/volumio/node_modules/sax/lib/sax.js:625:35) Jun 12 19:46:41 volumio volumio[10561]: at emitNode (/volumio/node_modules/sax/lib/sax.js:630:5) Jun 12 19:46:41 volumio volumio[10561]: at closeTag (/volumio/node_modules/sax/lib/sax.js:890:7) Jun 12 19:46:41 volumio volumio[10561]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1437:13) Jun 12 19:46:41 volumio volumio[10561]: at exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 12 19:46:41 volumio volumio[10561]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 12 19:46:41 volumio volumio[10561]: at exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 12 19:46:41 volumio volumio[10561]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 12 19:46:41 volumio volumio[10561]: at IncomingMessage.emit (node:events:526:35) Jun 12 19:46:41 volumio volumio[10561]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 12 19:46:41 volumio volumio[10561]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Jun 12 19:46:41 volumio volumio[10561]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:46:41 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 19:46:41 volumio dbus-daemon[933]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1628' (uid=0 pid=20756 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 19:46:41 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 19:46:41 volumio dbus-daemon[933]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 19:46:41 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 19:46:41 volumio setdatetime-helper.sh[20744]: Time is already synchronized. Jun 12 19:46:41 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 19:46:41 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 19:46:41 volumio sudo[20762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-12 19:45' Jun 12 19:46:41 volumio sudo[20762]: 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"