-- Logs begin at Sun 2024-03-31 17:21:51 CEST, end at Fri 2024-04-12 21:10:17 CEST. -- Apr 12 21:09:10 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::ClearQueue Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::serviceStop Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::serviceStop Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:10 volumio volumio[935]: info: ControllerMpd::stop Apr 12 21:09:10 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::addQueueItems Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::addQueueItems Apr 12 21:09:10 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:10 volumio volumio[935]: info: Adding Item to queue: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEguide_builder%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UCfM3zsQsOnfWNUppiycmBuw%22%7D%7D Apr 12 21:09:10 volumio volumio[935]: info: Exploding uri youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEguide_builder%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UCfM3zsQsOnfWNUppiycmBuw%22%7D%7D in service youtube2 Apr 12 21:09:10 volumio volumio[935]: info: [youtube2-browse] explodeUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEguide_builder%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UCfM3zsQsOnfWNUppiycmBuw%22%7D%7D Apr 12 21:09:10 volumio volumio[935]: info: Apr 12 21:09:10 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:10 volumio volumio[935]: info: sendMpdCommand stop took 13 milliseconds Apr 12 21:09:10 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:10 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:10 volumio volumio[935]: info: sendMpdCommand status took 0 milliseconds Apr 12 21:09:10 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:10 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:10 volumio volumio[935]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 21:09:10 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:10 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:10 volumio volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sia - I Forgive You","artist":"Sia","album":"YouTube","uri":"https://rr1---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970512&ei=sIYZZpHUKpaJ6dsPqOuO8Ac&ip=88.146.211.253&id=o-AMxzke53B2Ohz3xZFvVlTjPOCIQlX7NkeeXHoztiVO46&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=bu&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sne6&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a34-uGyy2KXlBDdDw8RravCS6lF0VPvyHK0zyYay0Yrh10YYedTOkXpwOfH3v828-LIPfITpJcj&spc=UWF9f_IDxLd7BX9yWjePdkPyY2NNsrZ2AVY4GszGTypYSDS8Lg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1ouOpv9vEKY2jcihzXC-5N0Q&gir=yes&clen=8603159&dur=259.961&lmt=1712899525908708&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=8Y8dqTOVLU62EQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgRqnCQsLQm78LH-_iEfhX2WubATOMZruh8l-qA01pg3YCIQCw9nXA34htT-SRhjPgSAvdyW6BkVzOGew7C9w6xugVqQ%3D%3D&sig=AJfQdSswRgIhANyo4Axugz8avPnPS-m8IlWPvrVV1Yfe5yXlQIN1TLxUAiEAvfXoPhk6c28Lt6i8RXxRiDFWDiz60IzeO_hevFnKI6A%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 12 21:09:10 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:10 volumio volumio[935]: info: No code Apr 12 21:09:10 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:10 volumio volumio[935]: info: ------------------------------ 8ms Apr 12 21:09:10 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:10 volumio volumio[935]: InnertubeError: Something went wrong at ChannelHeaderLinksView! Apr 12 21:09:10 volumio volumio[935]: This is a bug, please report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues Apr 12 21:09:10 volumio volumio[935]: at Function._Parser_printError (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18226:16) Apr 12 21:09:10 volumio volumio[935]: at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18059:70) Apr 12 21:09:10 volumio volumio[935]: at new C4TabbedHeader (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:10383:42) Apr 12 21:09:10 volumio volumio[935]: at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) Apr 12 21:09:10 volumio volumio[935]: at Function.parse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39) Apr 12 21:09:10 volumio volumio[935]: at Function.parseResponse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:17920:39) Apr 12 21:09:10 volumio volumio[935]: at EndpointModel._EndpointModel_doGetContents (/data/plugins/music_service/youtube2/dist/lib/model/EndpointModel.js:39:49) Apr 12 21:09:10 volumio volumio[935]: at runMicrotasks () Apr 12 21:09:10 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 12 21:09:10 volumio volumio[935]: at async GenericViewHandler.getTracksOnExplode (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:71:28) Apr 12 21:09:10 volumio volumio[935]: at async GenericViewHandler.explode (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/ExplodableViewHandler.js:14:24) Apr 12 21:09:10 volumio volumio[935]: at async BrowseController.explodeUri (/data/plugins/music_service/youtube2/dist/lib/controller/browse/index.js:70:20) { Apr 12 21:09:10 volumio volumio[935]: info: { Apr 12 21:09:10 volumio volumio[935]: stack: 'Error: Type mismatch, got ChannelHeaderLinksView but expected ChannelHeaderLinks\n' + Apr 12 21:09:10 volumio volumio[935]: ' at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18053:19)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at new C4TabbedHeader (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:10383:42)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at Function.parse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at Function.parseResponse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:17920:39)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at EndpointModel._EndpointModel_doGetContents (/data/plugins/music_service/youtube2/dist/lib/model/EndpointModel.js:39:49)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at runMicrotasks ()\n' + Apr 12 21:09:10 volumio volumio[935]: ' at processTicksAndRejections (internal/process/task_queues.js:93:5)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at async GenericViewHandler.getTracksOnExplode (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:71:28)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at async GenericViewHandler.explode (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/ExplodableViewHandler.js:14:24)\n' + Apr 12 21:09:10 volumio volumio[935]: ' at async BrowseController.explodeUri (/data/plugins/music_service/youtube2/dist/lib/controller/browse/index.js:70:20)' Apr 12 21:09:10 volumio volumio[935]: }, Apr 12 21:09:10 volumio volumio[935]: date: 2024-04-12T19:09:10.654Z, Apr 12 21:09:10 volumio volumio[935]: version: '0.3.3' Apr 12 21:09:10 volumio volumio[935]: } Apr 12 21:09:10 volumio volumio[935]: info: camilladsp spawned new process with pid 15374, instance 1, run: true Apr 12 21:09:11 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:11 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:11 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:11 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::play index 0 Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::play index undefined Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:11 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:11 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Apr 12 21:09:11 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:11 volumio volumio[935]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_wN3wl473M0%22%2C%22playlistId%22%3A%22PLN1mxegxWPd0FFhdUaOFA0IVF2-FmXIoC%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Eminem%2C%20Dr.%20Dre%2C%20%26%2050%20Cent%20-%20Crack%20A%20Bottle%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22EminemMusic%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F_wN3wl473M0%2Fhqdefault.jpg%3Fsqp%3D-oaymwEjCNACELwBSFryq4qpAxUIARUAAAAAGAElAADIQj0AgKJDeAE%3D%26rs%3DAOn4CLBMV295XbeAI9G3IcN7aVxgPySkQA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_wN3wl473M0%22%2C%22playlistId%22%3A%22PLN1mxegxWPd0FFhdUaOFA0IVF2-FmXIoC%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Apr 12 21:09:12 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:12 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:12 volumio volumio[935]: info: sendMpdCommand stop took 1 milliseconds Apr 12 21:09:12 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 21:09:12 volumio volumio[935]: info: Apr 12 21:09:12 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:12 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:12 volumio volumio[935]: info: sendMpdCommand clear took 0 milliseconds Apr 12 21:09:12 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970552&ei=2IYZZr33B6uC6dsPsPCR0A4&ip=88.146.211.253&id=o-AN55iZlhGoQ5MpK1TuukAatxbG9-Bq5ng_n_XYPF_Ozw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=86&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sn7r&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a148XY7oaLT4ugS4zfVOXW2wFjEmgErBFcpYkZ1Zt-uTVTrXX170wjJMMLb_-A-Nd-OWvXYgZ1S&spc=UWF9f34vacypCFAJSKJ102cIL5j1YiLnoTbStjFIEpM3IZE8kw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qam9J0EyxvkgmyRnPBrYvggQ&gir=yes&clen=4781373&dur=303.481&lmt=1700791760229212&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=AViqwTzmkjZxhg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRAIgdhqaEfrdqCXYXPX4jz_3EGifR7sJQ4wHrUrBqPmjMLQCIHYRx0oJ76eTQtgIrG30iVeWzNKB_U1l5kPefOM3EySV&sig=AJfQdSswRAIgMXeyncafxx0bavkf9SP5q7T6SLTpy-Cj5wW7jStniq0CIF9zRbn1WMuLI3iI9PJgOMS3gXgl9AK4UaEqI84X1I8K&cver=2.20230104.01.00&t.YouTube" Apr 12 21:09:12 volumio volumio[935]: error: updateQueue error: null Apr 12 21:09:12 volumio volumio[935]: info: Apr 12 21:09:12 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:12 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:12 volumio volumio[935]: info: ------------------------------ 2ms Apr 12 21:09:12 volumio volumio[935]: info: sendMpdCommand addid "https://rr2---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970552&ei=2IYZZr33B6uC6dsPsPCR0A4&ip=88.146.211.253&id=o-AN55iZlhGoQ5MpK1TuukAatxbG9-Bq5ng_n_XYPF_Ozw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=86&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sn7r&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a148XY7oaLT4ugS4zfVOXW2wFjEmgErBFcpYkZ1Zt-uTVTrXX170wjJMMLb_-A-Nd-OWvXYgZ1S&spc=UWF9f34vacypCFAJSKJ102cIL5j1YiLnoTbStjFIEpM3IZE8kw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qam9J0EyxvkgmyRnPBrYvggQ&gir=yes&clen=4781373&dur=303.481&lmt=1700791760229212&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=AViqwTzmkjZxhg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRAIgdhqaEfrdqCXYXPX4jz_3EGifR7sJQ4wHrUrBqPmjMLQCIHYRx0oJ76eTQtgIrG30iVeWzNKB_U1l5kPefOM3EySV&sig=AJfQdSswRAIgMXeyncafxx0bavkf9SP5q7T6SLTpy-Cj5wW7jStniq0CIF9zRbn1WMuLI3iI9PJgOMS3gXgl9AK4UaEqI84X1I8K&cver=2.20230104.01.00&t.YouTube" took 1 milliseconds Apr 12 21:09:12 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:12 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:12 volumio volumio[935]: info: Apr 12 21:09:12 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:12 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:12 volumio volumio[935]: info: ------------------------------ 2ms Apr 12 21:09:12 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:12 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Apr 12 21:09:12 volumio volumio[935]: info: ------------------------------ 1ms Apr 12 21:09:12 volumio volumio[935]: info: sendMpdCommand play took 2 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:13 volumio volumio[935]: info: Apr 12 21:09:13 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 48000 Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 48000 Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:13 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:13 volumio volumio[935]: info: Apr 12 21:09:13 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand status took 29 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand status took 30 milliseconds Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand status took 6 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand status took 4 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:13 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:13 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:13 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":303,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eminem, Dr. Dre, & 50 Cent - Crack A Bottle (Official Music Video)","artist":"EminemVEVO","album":"YouTube","uri":"https://rr2---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970552&ei=2IYZZr33B6uC6dsPsPCR0A4&ip=88.146.211.253&id=o-AN55iZlhGoQ5MpK1TuukAatxbG9-Bq5ng_n_XYPF_Ozw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=86&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sn7r&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a148XY7oaLT4ugS4zfVOXW2wFjEmgErBFcpYkZ1Zt-uTVTrXX170wjJMMLb_-A-Nd-OWvXYgZ1S&spc=UWF9f34vacypCFAJSKJ102cIL5j1YiLnoTbStjFIEpM3IZE8kw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qam9J0EyxvkgmyRnPBrYvggQ&gir=yes&clen=4781373&dur=303.481&lmt=1700791760229212&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=AViqwTzmkjZxhg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRAIgdhqaEfrdqCXYXPX4jz_3EGifR7sJQ4wHrUrBqPmjMLQCIHYRx0oJ76eTQtgIrG30iVeWzNKB_U1l5kPefOM3EySV&sig=AJfQdSswRAIgMXeyncafxx0bavkf9SP5q7T6SLTpy-Cj5wW7jStniq0CIF9zRbn1WMuLI3iI9PJgOMS3gXgl9AK4UaEqI84X1I8K&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 12 21:09:13 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:13 volumio volumio[935]: info: ------------------------------ 40ms Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 21:09:13 volumio volumio[935]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:13 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:13 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:13 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:13 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:13 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1355,"duration":303,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eminem, Dr. Dre, & 50 Cent - Crack A Bottle (Official Music Video)","artist":"EminemVEVO","album":"YouTube","uri":"https://rr2---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970552&ei=2IYZZr33B6uC6dsPsPCR0A4&ip=88.146.211.253&id=o-AN55iZlhGoQ5MpK1TuukAatxbG9-Bq5ng_n_XYPF_Ozw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=86&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sn7r&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a148XY7oaLT4ugS4zfVOXW2wFjEmgErBFcpYkZ1Zt-uTVTrXX170wjJMMLb_-A-Nd-OWvXYgZ1S&spc=UWF9f34vacypCFAJSKJ102cIL5j1YiLnoTbStjFIEpM3IZE8kw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qam9J0EyxvkgmyRnPBrYvggQ&gir=yes&clen=4781373&dur=303.481&lmt=1700791760229212&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=AViqwTzmkjZxhg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRAIgdhqaEfrdqCXYXPX4jz_3EGifR7sJQ4wHrUrBqPmjMLQCIHYRx0oJ76eTQtgIrG30iVeWzNKB_U1l5kPefOM3EySV&sig=AJfQdSswRAIgMXeyncafxx0bavkf9SP5q7T6SLTpy-Cj5wW7jStniq0CIF9zRbn1WMuLI3iI9PJgOMS3gXgl9AK4UaEqI84X1I8K&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 12 21:09:13 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Apr 12 21:09:13 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:13 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:13 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:13 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:13 volumio volumio[935]: info: ------------------------------ 25ms Apr 12 21:09:13 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 12 21:09:13 volumio volumio[935]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 12 21:09:15 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 172) (YouTube) Incoming message: 'noop' Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 172) (YouTube) Not handled: 'noop' Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=172&CI=0&RID=rpc&SID=F6BC491DA00C7F97&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=BsPO_BdtUEpNWO34Z7WOW3Auk7I_VnR7&id=841ac1e2-0b44-4963-85d4-d46d5bf22d36&loungeIdToken=AGdO5p-PnJntkNBHItShnA44JcUm_hiMdRnJ7F1LQR9JxSbOg9szIRgvmEbgP5RD6cakakayM06V2I-LjMcP4ADwy6as2F1KJvTvl68Bb9zm-15x-NX1s9Q&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=0457af5d25a1 Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] RPC connection established. Apr 12 21:09:15 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 173) (YouTube) Incoming message: 'noop' Apr 12 21:09:15 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 173) (YouTube) Not handled: 'noop' Apr 12 21:09:25 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:09:25 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 172) (YouTube Music) Incoming message: 'noop' Apr 12 21:09:25 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 172) (YouTube Music) Not handled: 'noop' Apr 12 21:09:36 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Apr 12 21:09:36 volumio volumio[935]: info: [bandcamp-browse] browseUri: bandcamp Apr 12 21:09:39 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:39 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:41 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:09:41 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 174) (YouTube) Incoming message: 'noop' Apr 12 21:09:41 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 174) (YouTube) Not handled: 'noop' Apr 12 21:09:46 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::ClearQueue Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::serviceStop Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::serviceStop Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:46 volumio volumio[935]: info: ControllerMpd::stop Apr 12 21:09:46 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::addQueueItems Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::addQueueItems Apr 12 21:09:46 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:46 volumio volumio[935]: info: Adding Item to queue: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview Apr 12 21:09:46 volumio volumio[935]: info: Exploding uri bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview in service bandcamp Apr 12 21:09:46 volumio volumio[935]: info: [bandcamp-browse] explodeUri: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview Apr 12 21:09:46 volumio volumio[935]: info: Apr 12 21:09:46 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:46 volumio volumio[935]: info: sendMpdCommand stop took 17 milliseconds Apr 12 21:09:46 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:46 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:46 volumio volumio[935]: info: sendMpdCommand status took 1 milliseconds Apr 12 21:09:46 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:46 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:46 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 21:09:46 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:46 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:46 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:46 volumio volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eminem, Dr. Dre, & 50 Cent - Crack A Bottle (Official Music Video)","artist":"EminemVEVO","album":"YouTube","uri":"https://rr2---sn-8vq54voxj1-cg9l.googlevideo.com/videoplayback?expire=1712970552&ei=2IYZZr33B6uC6dsPsPCR0A4&ip=88.146.211.253&id=o-AN55iZlhGoQ5MpK1TuukAatxbG9-Bq5ng_n_XYPF_Ozw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=86&mm=31%2C29&mn=sn-8vq54voxj1-cg9l%2Csn-2gb7sn7r&ms=au%2Crdu&mv=m&mvi=2&pl=24&gcr=cz&initcwndbps=1123750&bui=AaUN6a148XY7oaLT4ugS4zfVOXW2wFjEmgErBFcpYkZ1Zt-uTVTrXX170wjJMMLb_-A-Nd-OWvXYgZ1S&spc=UWF9f34vacypCFAJSKJ102cIL5j1YiLnoTbStjFIEpM3IZE8kw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qam9J0EyxvkgmyRnPBrYvggQ&gir=yes&clen=4781373&dur=303.481&lmt=1700791760229212&mt=1712948459&fvip=4&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=AViqwTzmkjZxhg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRAIgdhqaEfrdqCXYXPX4jz_3EGifR7sJQ4wHrUrBqPmjMLQCIHYRx0oJ76eTQtgIrG30iVeWzNKB_U1l5kPefOM3EySV&sig=AJfQdSswRAIgMXeyncafxx0bavkf9SP5q7T6SLTpy-Cj5wW7jStniq0CIF9zRbn1WMuLI3iI9PJgOMS3gXgl9AK4UaEqI84X1I8K&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 12 21:09:46 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:46 volumio volumio[935]: info: No code Apr 12 21:09:46 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:46 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:46 volumio volumio[935]: info: ------------------------------ 9ms Apr 12 21:09:46 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:47 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::ClearQueue Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::addQueueItems Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::addQueueItems Apr 12 21:09:47 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:47 volumio volumio[935]: info: Adding Item to queue: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview Apr 12 21:09:47 volumio volumio[935]: info: Exploding uri bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview in service bandcamp Apr 12 21:09:47 volumio volumio[935]: info: [bandcamp-browse] explodeUri: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview Apr 12 21:09:47 volumio volumio[935]: info: camilladsp spawned new process with pid 15499, instance 1, run: true Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::play index 0 Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::play index undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:47 volumio volumio[935]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview@mediaItemRef=t2977088820@track=1@artistUrl=https%3A%2F%2Flordspikeheart.bandcamp.com@albumUrl=https%3A%2F%2Flordspikeheart.bandcamp.com%2Falbum%2Fthe-adept Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand stop took 1 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3" Apr 12 21:09:47 volumio volumio[935]: error: updateQueue error: null Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3" took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 4ms Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:47 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::play index 0 Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::play index undefined Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Apr 12 21:09:47 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:47 volumio volumio[935]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Ffeatures%2Flord-spikeheart-the-adept-interview@mediaItemRef=t2977088820@track=1@artistUrl=https%3A%2F%2Flordspikeheart.bandcamp.com@albumUrl=https%3A%2F%2Flordspikeheart.bandcamp.com%2Falbum%2Fthe-adept Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand stop took 1 milliseconds Apr 12 21:09:47 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand status took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3" Apr 12 21:09:47 volumio volumio[935]: error: updateQueue error: null Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 4ms Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3" took 2 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:47 volumio volumio[935]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 9ms Apr 12 21:09:47 volumio volumio[935]: info: Apr 12 21:09:47 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:47 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 6ms Apr 12 21:09:47 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:47 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Apr 12 21:09:47 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:47 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Apr 12 21:09:47 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:48 volumio volumio[935]: info: Apr 12 21:09:48 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:48 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 12 21:09:48 volumio volumio[935]: info: camilladsp stopping service pid 15499... Apr 12 21:09:48 volumio volumio[935]: info: camilladsp service terminated, instance 1 Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:48 volumio volumio[935]: info: camilladsp service started and running in background, instance 1 Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 12 21:09:48 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:48 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:48 volumio volumio[935]: info: Apr 12 21:09:48 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:48 volumio volumio[935]: info: sendMpdCommand status took 68 milliseconds Apr 12 21:09:48 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:48 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Apr 12 21:09:48 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:48 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:48 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:48 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:48 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":133,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TYVM ft SAIONJI BBBBBBB.","artist":"Lord Spikeheart","album":"The Adept","uri":"https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3","trackType":"mp3"} Apr 12 21:09:48 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:48 volumio volumio[935]: info: ------------------------------ 73ms Apr 12 21:09:48 volumio volumio[935]: error: FusionDsp - WebSocket error: [object Object] Apr 12 21:09:48 volumio volumio[935]: info: camilladsp spawned new process with pid 15516, instance 1, run: true Apr 12 21:09:48 volumio volumio[935]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 12 21:09:48 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:48 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:48 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:48 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:48 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":133,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TYVM ft SAIONJI BBBBBBB.","artist":"Lord Spikeheart","album":"The Adept","uri":"https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3","trackType":"mp3"} Apr 12 21:09:48 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Apr 12 21:09:48 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:48 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:48 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:48 volumio volumio[935]: info: ------------------------------ 57ms Apr 12 21:09:48 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:53 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:09:53 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 173) (YouTube Music) Incoming message: 'noop' Apr 12 21:09:53 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 173) (YouTube Music) Not handled: 'noop' Apr 12 21:09:56 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::ClearQueue Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::serviceStop Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::serviceStop Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:56 volumio volumio[935]: info: ControllerMpd::stop Apr 12 21:09:56 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::addQueueItems Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::addQueueItems Apr 12 21:09:56 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:56 volumio volumio[935]: info: Adding Item to queue: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 Apr 12 21:09:56 volumio volumio[935]: info: Exploding uri bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 in service bandcamp Apr 12 21:09:56 volumio volumio[935]: info: [bandcamp-browse] explodeUri: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 Apr 12 21:09:56 volumio volumio[935]: info: Apr 12 21:09:56 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:56 volumio volumio[935]: info: sendMpdCommand stop took 8 milliseconds Apr 12 21:09:56 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:56 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:56 volumio volumio[935]: info: sendMpdCommand status took 5 milliseconds Apr 12 21:09:56 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:56 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:56 volumio volumio[935]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 21:09:56 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:56 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:56 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:56 volumio volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TYVM ft SAIONJI BBBBBBB.","artist":"Lord Spikeheart","album":"The Adept","uri":"https://t4.bcbits.com/stream/b0053ef5d3c66a9a2fdcf78d9c44039c/mp3-128/2977088820?p=0&ts=1713031047&t=3a77b6d0bb288b1c66f3120446ed227adc0e560e&token=1713031047_ac4b848f589bbb6c6984e9069320c50217fd28d4&t.mp3","trackType":"mp3"} Apr 12 21:09:56 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:56 volumio volumio[935]: info: No code Apr 12 21:09:56 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:56 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:56 volumio volumio[935]: info: ------------------------------ 12ms Apr 12 21:09:56 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:57 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::ClearQueue Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::addQueueItems Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::addQueueItems Apr 12 21:09:57 volumio volumio[935]: info: Preload queue cleared Apr 12 21:09:57 volumio volumio[935]: info: Adding Item to queue: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 Apr 12 21:09:57 volumio volumio[935]: info: Exploding uri bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 in service bandcamp Apr 12 21:09:57 volumio volumio[935]: info: [bandcamp-browse] explodeUri: bandcamp/article@inSection=1/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024 Apr 12 21:09:57 volumio volumio[935]: info: camilladsp spawned new process with pid 15539, instance 1, run: true Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::play index 0 Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::play index undefined Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:57 volumio volumio[935]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024@mediaItemRef=t1190056712@track=9@artistUrl=https%3A%2F%2Fdianebirch-legere.bandcamp.com@albumUrl=https%3A%2F%2Fdianebirch-legere.bandcamp.com%2Falbum%2Fflying-on-abraham Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand stop took 0 milliseconds Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 21:09:57 volumio volumio[935]: info: Apr 12 21:09:57 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:57 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand clear took 1 milliseconds Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3" Apr 12 21:09:57 volumio volumio[935]: error: updateQueue error: null Apr 12 21:09:57 volumio volumio[935]: info: Apr 12 21:09:57 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:57 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:57 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3" took 2 milliseconds Apr 12 21:09:57 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:57 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:57 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:57 volumio volumio[935]: info: Apr 12 21:09:57 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:57 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:57 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Apr 12 21:09:57 volumio volumio[935]: info: ------------------------------ 2ms Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Apr 12 21:09:57 volumio volumio[935]: info: Apr 12 21:09:57 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:57 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:57 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:57 volumio volumio[935]: info: Apr 12 21:09:57 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand status took 30 milliseconds Apr 12 21:09:57 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:57 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:57 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Used To Lovin' You","artist":"Diane Birch","album":"Flying On Abraham","uri":"https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3","trackType":"mp3"} Apr 12 21:09:57 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:57 volumio volumio[935]: info: ------------------------------ 36ms Apr 12 21:09:57 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 21:09:57 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:57 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:57 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:57 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Used To Lovin' You","artist":"Diane Birch","album":"Flying On Abraham","uri":"https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3","trackType":"mp3"} Apr 12 21:09:57 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Apr 12 21:09:57 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:57 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:57 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:57 volumio volumio[935]: info: ------------------------------ 16ms Apr 12 21:09:57 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 12 21:09:57 volumio volumio[935]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::saveQueue Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::play index 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::stop Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::serviceStop Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::serviceStop Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::stop Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand stop took 4 milliseconds Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::play index undefined Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fessential-releases%2Fessential-releases-april-12-2024@mediaItemRef=t1190056712@track=9@artistUrl=https%3A%2F%2Fdianebirch-legere.bandcamp.com@albumUrl=https%3A%2F%2Fdianebirch-legere.bandcamp.com%2Falbum%2Fflying-on-abraham Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand status took 7 milliseconds Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand stop took 2 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:58 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand clear took 2 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3" Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 16ms Apr 12 21:09:58 volumio volumio[935]: error: updateQueue error: null Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:58 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 8ms Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3" took 7 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:58 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:58 volumio volumio[935]: verbose: MPD COMMAND [object Object] Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces system playlist update Apr 12 21:09:58 volumio volumio[935]: info: Ignoring MPD Status Update Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 6ms Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 3ms Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:58 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: Apr 12 21:09:58 volumio volumio[935]: error: FusionDsp - invalid sample rate Apr 12 21:09:58 volumio volumio[935]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 12 21:09:58 volumio volumio[935]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 12 21:09:58 volumio volumio[935]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 12 21:09:58 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:09:58 volumio volumio[935]: info: Apr 12 21:09:58 volumio volumio[935]: ---------------------------- MPD announces state update: player Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand status took 22 milliseconds Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::getState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand status took 3 milliseconds Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseState Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Used To Lovin' You","artist":"Diane Birch","album":"Flying On Abraham","uri":"https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3","trackType":"mp3"} Apr 12 21:09:58 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 27ms Apr 12 21:09:58 volumio volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 21:09:58 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Apr 12 21:09:58 volumio volumio[935]: info: ControllerMpd::pushState Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::servicePushState Apr 12 21:09:58 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Apr 12 21:09:58 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Used To Lovin' You","artist":"Diane Birch","album":"Flying On Abraham","uri":"https://t4.bcbits.com/stream/2accccd5c28cdcd8bdd139c74c5d0c63/mp3-128/1190056712?p=0&ts=1713032749&t=ff6aa0966352a04e7ee6b12b4780aef081b9d37c&token=1713032749_1479aac92b51f4d10ce71124c716e56eba7fbf1c&t.mp3","trackType":"mp3"} Apr 12 21:09:58 volumio volumio[935]: verbose: CURRENT POSITION 0 Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Apr 12 21:09:58 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:58 volumio volumio[935]: info: CoreStateMachine::pushState Apr 12 21:09:58 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Apr 12 21:09:58 volumio volumio[935]: info: ------------------------------ 12ms Apr 12 21:09:58 volumio volumio[935]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 12 21:09:58 volumio volumio[935]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Apr 12 21:10:09 volumio volumio[935]: verbose: ----------------------------------- Apr 12 21:10:09 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 175) (YouTube) Incoming message: 'noop' Apr 12 21:10:09 volumio volumio[935]: verbose: [yt-cast-receiver] (AID: 175) (YouTube) Not handled: 'noop' Apr 12 21:10:11 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 21:10:11 volumio volumio[935]: info: Preload queue cleared Apr 12 21:10:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 21:10:15 volumio volumio[935]: info: Preload queue cleared Apr 12 21:10:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 21:10:17 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 21:10:17 volumio volumio[935]: TypeError: Cannot read property 'length' of undefined Apr 12 21:10:17 volumio volumio[935]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 12 21:10:17 volumio volumio[935]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 12 21:10:17 volumio volumio[935]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 12 21:10:17 volumio volumio[935]: at Parser.emit (events.js:315:20) Apr 12 21:10:17 volumio volumio[935]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 12 21:10:17 volumio volumio[935]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 12 21:10:17 volumio volumio[935]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 12 21:10:17 volumio volumio[935]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 12 21:10:17 volumio volumio[935]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 12 21:10:17 volumio volumio[935]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 12 21:10:17 volumio volumio[935]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 12 21:10:17 volumio volumio[935]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 12 21:10:17 volumio volumio[935]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 12 21:10:17 volumio volumio[935]: at IncomingMessage.emit (events.js:327:22) Apr 12 21:10:17 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 12 21:10:17 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 12 21:10:17 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 21:10:17 volumio sudo[15605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 21:09 Apr 12 21:10:17 volumio sudo[15605]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"