-- Logs begin at Fri 2024-11-08 17:04:03 CET, end at Sun 2025-01-19 00:49:08 CET. -- Jan 19 00:48:00 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:02 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:02 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:05 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:05 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i6447 Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i48984 Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i39218 Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i42406 Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i4271 Jan 19 00:48:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i34811 Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i6447 in service upnp_browser Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i48984 in service upnp_browser Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i39218 in service upnp_browser Jan 19 00:48:05 rivo volumio[3182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i42406 in service upnp_browser Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i4271 in service upnp_browser Jan 19 00:48:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*i34811 in service upnp_browser Jan 19 00:48:08 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:08 rivo volumio[3182]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::ClearQueue Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::stop Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::clearPlayQueue Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::saveQueue Jan 19 00:48:08 rivo volumio[3182]: info: CoreCommandRouter::volumioPushQueue Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::addQueueItems Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::addQueueItems Jan 19 00:48:08 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:08 rivo volumio[3182]: info: Adding Item to queue: upnp/folder/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*p302 Jan 19 00:48:08 rivo volumio[3182]: info: Exploding uri upnp/folder/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4731$*p302 in service upnp_browser Jan 19 00:48:08 rivo volumio[3182]: info: CoreCommandRouter::volumioPushQueue Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::saveQueue Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::updateTrackBlock Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::getTrackBlock Jan 19 00:48:08 rivo volumio[3182]: info: CoreCommandRouter::volumioPlay Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::play index 0 Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::stop Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::play index undefined Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::getTrack 0 Jan 19 00:48:08 rivo volumio[3182]: info: CoreStateMachine::startPlaybackTimer Jan 19 00:48:08 rivo volumio[3182]: info: CorePlayQueue::getTrack 0 Jan 19 00:48:08 rivo volumio[3182]: info: [1737244088402] ControllerUPNPBrowser::clearAddPlayTrack Jan 19 00:48:08 rivo volumio[3182]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 00:48:19 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 00:48:19 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 00:48:19 rivo volumio[3182]: info: Discovery: Getting this device information Jan 19 00:48:19 rivo volumio[3182]: info: CoreCommandRouter::volumioGetState Jan 19 00:48:19 rivo volumio[3182]: info: CorePlayQueue::getTrack 0 Jan 19 00:48:19 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 00:48:49 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 00:48:49 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 00:48:49 rivo volumio[3182]: info: Discovery: Getting this device information Jan 19 00:48:49 rivo volumio[3182]: info: CoreCommandRouter::volumioGetState Jan 19 00:48:49 rivo volumio[3182]: info: CorePlayQueue::getTrack 0 Jan 19 00:48:49 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 00:48:52 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:53 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:54 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:54 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:55 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:55 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:48:58 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:48:58 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:49:01 rivo volumio[3182]: info: CorePlayQueue::getTrack 0 Jan 19 00:49:01 rivo volumio[3182]: info: CorePlayQueue::getTrack 1 Jan 19 00:49:01 rivo volumio[3182]: info: Prefetching next song Jan 19 00:49:01 rivo volumio[3182]: info: Doing Prefetch in UPNP Jan 19 00:49:01 rivo volumio[3182]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.110:9790/minimserver/*/Multimedia/FLAC/JAZZ/Art*20Blakey/Art*20Blakey*20-*20A*20Night*20At*20Birdland*20Vol.*201*20(2014)*20*5b24-192*20HD*20FLAC*5d/02-Split*20Kick*20(Live*20At*20Birdland,*20New*20York-1954).flac" Jan 19 00:49:02 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:49:02 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:49:03 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:49:03 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:49:05 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:49:05 rivo volumio[3182]: info: Preload queue cleared Jan 19 00:49:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i59824 Jan 19 00:49:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i27330 Jan 19 00:49:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i42407 Jan 19 00:49:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i38561 Jan 19 00:49:05 rivo volumio[3182]: info: Preloading song: upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i13321 Jan 19 00:49:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i59824 in service upnp_browser Jan 19 00:49:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i27330 in service upnp_browser Jan 19 00:49:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i42407 in service upnp_browser Jan 19 00:49:05 rivo volumio[3182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 19 00:49:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i38561 in service upnp_browser Jan 19 00:49:05 rivo volumio[3182]: info: Exploding uri upnp/http://192.168.1.110:9791/82b652b3-c10c-4a6d-8f17-ce60eec3a061/upnp.org-ContentDirectory-1/control@0$folders$f4561$f4681$f4729$f4732$*i13321 in service upnp_browser Jan 19 00:49:05 rivo volumio[3182]: info: CoreStateMachine::startPlaybackTimer Jan 19 00:49:05 rivo volumio[3182]: info: CorePlayQueue::getTrack 1 Jan 19 00:49:06 rivo volumio[3182]: info: CoreStateMachine::pushState Jan 19 00:49:06 rivo volumio[3182]: info: CorePlayQueue::getTrack 1 Jan 19 00:49:06 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 00:49:06 rivo volumio[3182]: info: CoreCommandRouter::volumioPushState Jan 19 00:49:06 rivo volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jan 19 00:49:06 rivo volumio[3182]: info: MRS: Pushing multiroomSync output Jan 19 00:49:06 rivo volumio[3182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 19 00:49:07 rivo volumio[3182]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 00:49:07 rivo volumio[3182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 00:49:07 rivo volumio[3182]: TypeError: Cannot read property 'length' of undefined Jan 19 00:49:07 rivo volumio[3182]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 19 00:49:07 rivo volumio[3182]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 19 00:49:07 rivo volumio[3182]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 19 00:49:07 rivo volumio[3182]: at Parser.emit (events.js:400:28) Jan 19 00:49:07 rivo volumio[3182]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 19 00:49:07 rivo volumio[3182]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 19 00:49:07 rivo volumio[3182]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 19 00:49:07 rivo volumio[3182]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 19 00:49:07 rivo volumio[3182]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 19 00:49:07 rivo volumio[3182]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 19 00:49:07 rivo volumio[3182]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 19 00:49:07 rivo volumio[3182]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 19 00:49:07 rivo volumio[3182]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 19 00:49:07 rivo volumio[3182]: at IncomingMessage.emit (events.js:412:35) Jan 19 00:49:07 rivo volumio[3182]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 19 00:49:07 rivo volumio[3182]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 19 00:49:07 rivo volumio[3182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 00:49:08 rivo sudo[4822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 00:48 Jan 19 00:49:08 rivo sudo[4822]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"