-- Logs begin at Thu 2019-02-14 11:12:06 CET, end at Sun 2024-08-18 10:38:15 CEST. -- Aug 18 10:37:04 volumio3 volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 10:37:04 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 10:37:05 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 10:37:05 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 10:37:06 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 18 10:37:06 volumio3 volumio[989]: info: Received Get System Version Aug 18 10:37:06 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 10:37:19 volumio3 volumio[989]: error: error Aug 18 10:37:19 volumio3 volumio[989]: error: error Aug 18 10:37:25 volumio3 volumio[989]: Upnp client error: Error: This socket has been ended by the other party Aug 18 10:37:39 volumio3 volumio[989]: info: CoreCommandRouter::volumioGetState Aug 18 10:37:39 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:37:48 volumio3 volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 18 10:37:48 volumio3 volumio[989]: info: CoreStateMachine::getQueue Aug 18 10:37:48 volumio3 volumio[989]: info: CorePlayQueue::getQueue Aug 18 10:37:53 volumio3 volumio[989]: info: CoreCommandRouter::volumioGetState Aug 18 10:37:53 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:37:57 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 10:37:57 volumio3 volumio[989]: info: Preload queue cleared Aug 18 10:37:59 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 10:37:59 volumio3 volumio[989]: info: Preload queue cleared Aug 18 10:38:07 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 10:38:07 volumio3 volumio[989]: info: Preload queue cleared Aug 18 10:38:07 volumio3 volumio[989]: info: [1723970287482] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Aug 18 10:38:08 volumio3 volumio[989]: info: [1723970288435] [RadioParadise] received new metadata: {"time":179,"artist":"The Decemberists","title":"12-17-12","album":"What a Terrible World, What a Beautiful World","year":"2015","cover":"https://img.radioparadise.com/covers/l/B00P33GA7Q.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00P33GA7Q.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00P33GA7Q.jpg"} Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:38:08 volumio3 volumio[989]: info: CoreCommandRouter::servicePushState Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:38:08 volumio3 volumio[989]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00P33GA7Q.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"12-17-12","title":"12-17-12","artist":"The Decemberists","album":"What a Terrible World, What a Beautiful World","streaming":true,"disableUiControls":true,"duration":184,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 18 10:38:08 volumio3 volumio[989]: verbose: CURRENT POSITION 0 Aug 18 10:38:08 volumio3 volumio[989]: info: CoreStateMachine::syncState stateService play Aug 18 10:38:08 volumio3 volumio[989]: info: CoreStateMachine::syncState currentStatus play Aug 18 10:38:08 volumio3 volumio[989]: info: Received an update from plugin. extracting info from payload Aug 18 10:38:08 volumio3 volumio[989]: info: CoreStateMachine::pushState Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:38:08 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 10:38:08 volumio3 volumio[989]: info: CoreCommandRouter::volumioPushState Aug 18 10:38:08 volumio3 volumio[989]: info: CoreStateMachine::pushState Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:38:08 volumio3 volumio[989]: info: CoreCommandRouter::volumioPushState Aug 18 10:38:08 volumio3 volumio[989]: info: [1723970288607] [RadioParadise] setting new timer with duration of 184000 seconds. Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 0 Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getTrack 1 Aug 18 10:38:08 volumio3 volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 18 10:38:08 volumio3 volumio[989]: info: CoreStateMachine::getQueue Aug 18 10:38:08 volumio3 volumio[989]: info: CorePlayQueue::getQueue Aug 18 10:38:08 volumio3 volumio[989]: error: error Aug 18 10:38:09 volumio3 volumio[989]: error: error Aug 18 10:38:11 volumio3 volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 10:38:11 volumio3 volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 10:38:11 volumio3 volumio[989]: TypeError: Cannot read property 'length' of undefined Aug 18 10:38:11 volumio3 volumio[989]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 18 10:38:11 volumio3 volumio[989]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 18 10:38:11 volumio3 volumio[989]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 18 10:38:11 volumio3 volumio[989]: at Parser.emit (events.js:315:20) Aug 18 10:38:11 volumio3 volumio[989]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 18 10:38:11 volumio3 volumio[989]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 18 10:38:11 volumio3 volumio[989]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 18 10:38:11 volumio3 volumio[989]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 18 10:38:11 volumio3 volumio[989]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 18 10:38:11 volumio3 volumio[989]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 18 10:38:11 volumio3 volumio[989]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 18 10:38:11 volumio3 volumio[989]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 18 10:38:11 volumio3 volumio[989]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 18 10:38:11 volumio3 volumio[989]: at IncomingMessage.emit (events.js:327:22) Aug 18 10:38:11 volumio3 volumio[989]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 18 10:38:11 volumio3 volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 18 10:38:11 volumio3 volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 10:38:15 volumio3 sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 10:37 Aug 18 10:38:15 volumio3 sudo[2100]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"