-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Sat 2024-10-26 08:48:46 KST. -- Oct 26 08:47:00 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 08:47:13 volumio ntpd[758]: Soliciting pool server 194.0.5.123 Oct 26 08:47:13 volumio ntpd[758]: Soliciting pool server 193.123.243.2 Oct 26 08:47:13 volumio ntpd[758]: Soliciting pool server 121.174.142.82 Oct 26 08:47:13 volumio ntpd[758]: Soliciting pool server 121.174.142.81 Oct 26 08:47:14 volumio ntpd[758]: Soliciting pool server 175.195.167.194 Oct 26 08:47:15 volumio ntpd[758]: Soliciting pool server 175.193.3.234 Oct 26 08:47:15 volumio ntpd[758]: Soliciting pool server 3.39.176.65 Oct 26 08:47:15 volumio ntpd[758]: Soliciting pool server 39.118.108.191 Oct 26 08:47:15 volumio ntpd[758]: Soliciting pool server 106.247.248.106 Oct 26 08:47:16 volumio ntpd[758]: Soliciting pool server 2603:c022:8004:7800:65bf:c600:a739:1bee Oct 26 08:47:52 volumio ntpd[758]: receive: Unexpected origin timestamp 0xeac6ac88.e8d85b26 does not match aorg 0000000000.00000000 from server@175.195.167.194 xmt 0xeac6aca8.8d2438e4 Oct 26 08:47:52 volumio ntpd[758]: receive: Unexpected origin timestamp 0xeac6ac88.e8d6e600 does not match aorg 0000000000.00000000 from server@3.39.176.65 xmt 0xeac6aca8.8d7df1f4 Oct 26 08:47:52 volumio ntpd[758]: receive: Unexpected origin timestamp 0xeac6ac88.e8d40a75 does not match aorg 0000000000.00000000 from server@106.247.248.106 xmt 0xeac6aca8.8d9237ec Oct 26 08:47:52 volumio ntpd[758]: receive: Unexpected origin timestamp 0xeac6ac88.e8d9cfe5 does not match aorg 0000000000.00000000 from server@121.174.142.82 xmt 0xeac6aca8.8e281c0b Oct 26 08:47:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 08:47:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 08:47:54 volumio volumio[869]: info: Discovery: Getting this device information Oct 26 08:47:54 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:47:54 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:47:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 08:47:55 volumio volumio[869]: verbose: New Socket.io Connection to 192.168.0.22:3000 from 192.168.0.10 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 26 08:47:55 volumio volumio[869]: verbose: New Socket.io Connection to 192.168.0.22 from 192.168.0.10 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:47:55 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 08:47:55 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 08:47:55 volumio volumio[869]: info: Discovery: Getting this device information Oct 26 08:47:55 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:47:55 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:47:55 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 08:47:56 volumio volumio[869]: verbose: New Socket.io Connection to 192.168.0.22 from 192.168.0.10 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:47:56 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 08:47:56 volumio volumio[869]: info: Received Get System Info Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 08:47:56 volumio volumio[869]: info: Discovery: Getting this device information Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:47:56 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:47:56 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:47:56 volumio volumio[869]: info: Listing playlists Oct 26 08:47:56 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 08:48:07 volumio volumio[869]: verbose: New Socket.io Connection to 192.168.0.22 from 192.168.0.10 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:48:07 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 08:48:07 volumio volumio[869]: info: Received Get System Info Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 08:48:07 volumio volumio[869]: info: Discovery: Getting this device information Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:48:07 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:48:07 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:48:07 volumio volumio[869]: info: Listing playlists Oct 26 08:48:07 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 08:48:14 volumio volumio[869]: info: CoreCommandRouter::volumioGetQueue Oct 26 08:48:14 volumio volumio[869]: info: CoreStateMachine::getQueue Oct 26 08:48:14 volumio volumio[869]: info: CorePlayQueue::getQueue Oct 26 08:48:17 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 08:48:17 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 08:48:17 volumio volumio[869]: info: Discovery: Getting this device information Oct 26 08:48:17 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Oct 26 08:48:17 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Oct 26 08:48:17 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 08:48:19 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 26 08:48:19 volumio volumio[869]: info: Preload queue cleared Oct 26 08:48:35 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 08:48:35 volumio volumio[869]: info: Preload queue cleared Oct 26 08:48:37 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 08:48:37 volumio volumio[869]: info: Preload queue cleared Oct 26 08:48:42 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 08:48:42 volumio volumio[869]: info: Preload queue cleared Oct 26 08:48:46 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 08:48:46 volumio volumio[869]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:48:46 volumio volumio[869]: TypeError: Cannot read property 'length' of undefined Oct 26 08:48:46 volumio volumio[869]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 26 08:48:46 volumio volumio[869]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 26 08:48:46 volumio volumio[869]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 26 08:48:46 volumio volumio[869]: at Parser.emit (events.js:315:20) Oct 26 08:48:46 volumio volumio[869]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 26 08:48:46 volumio volumio[869]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 26 08:48:46 volumio volumio[869]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 26 08:48:46 volumio volumio[869]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 26 08:48:46 volumio volumio[869]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 26 08:48:46 volumio volumio[869]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 26 08:48:46 volumio volumio[869]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 26 08:48:46 volumio volumio[869]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 26 08:48:46 volumio volumio[869]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 26 08:48:46 volumio volumio[869]: at IncomingMessage.emit (events.js:327:22) Oct 26 08:48:46 volumio volumio[869]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 26 08:48:46 volumio volumio[869]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 26 08:48:46 volumio volumio[869]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:48:46 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 08:47 Oct 26 08:48:46 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"