Dec 24 22:09:02 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 24 22:09:02 volumiocentre volumio[1113]: info: [youtube2-browse] browseUri: youtube2/search@query=Mono%20inc/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22u___F6t4PbA%22%2C%22playlistId%22%3A%22PLEpjZ6a3i26dM326KopSE9Hc7LmNvbsvI%22%2C%22params%22%3A%22OAI%253D%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLPLEpjZ6a3i26dM326KopSE9Hc7LmNvbsvI%22%7D%7D%7D Dec 24 22:09:02 volumiocentre volumio[1113]: info: [youtube2] PlaylistModel is going to recursively fetch continuation items for playlist with endpoint: {"type":"browse","payload":{"browseId":"VLPLEpjZ6a3i26dM326KopSE9Hc7LmNvbsvI"}}). Dec 24 22:09:02 volumiocentre volumio[1113]: info: [youtube2] Fetched 1 continuation items. Dec 24 22:09:02 volumiocentre volumio[1113]: info: [youtube2] Total 1 continuation items fetched. Total items in playlist: 2. Dec 24 22:09:02 volumiocentre volumio[1113]: info: Preload queue cleared Dec 24 22:09:02 volumiocentre volumio[1113]: info: Preload queue cleared Dec 24 22:09:10 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:10Z" level=trace msg="received accesspoint ping" Dec 24 22:09:10 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:10Z" level=trace msg="received accesspoint pong ack" Dec 24 22:09:10 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:10Z" level=trace msg="sent dealer ping" Dec 24 22:09:10 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:10Z" level=trace msg="received dealer pong" Dec 24 22:09:13 volumiocentre volumio[1113]: info: Preload queue cleared Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::ClearQueue Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::stop Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::stPlaybackTimer Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::updateTrackBlock Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::getTrackBlock Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::serviceStop Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::serviceStop Dec 24 22:09:13 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Removed mpdStateListener Dec 24 22:09:13 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Removed volumioStateListener Dec 24 22:09:13 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Disabled Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 22:09:13 volumiocentre volumio[1113]: info: ControllerMpd::stop Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::clearPlayQueue Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::saveQueue Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushQueue Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::addQueueItems Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::addQueueItems Dec 24 22:09:13 volumiocentre volumio[1113]: info: Preload queue cleared Dec 24 22:09:13 volumiocentre volumio[1113]: info: Adding Item to queue: youtube2/search@query=Mono%20inc/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OALAAQE%253D%22%7D%7D%7D Dec 24 22:09:13 volumiocentre volumio[1113]: info: Exploding uri youtube2/search@query=Mono%20inc/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OALAAQE%253D%22%7D%7D%7D in service youtube2 Dec 24 22:09:13 volumiocentre volumio[1113]: info: [youtube2-browse] explodeUri: youtube2/search@query=Mono%20inc/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OALAAQE%253D%22%7D%7D%7D Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Volumio is not playing Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Clipped samples monitor stopped Dec 24 22:09:13 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 24 22:09:13 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:13 volumiocentre volumio[1113]: info: Dec 24 22:09:13 volumiocentre volumio[1113]: ---------------------------- MPD announces state update: player Dec 24 22:09:13 volumiocentre volumio[1113]: info: sendMpdCommand stop took 10 milliseconds Dec 24 22:09:13 volumiocentre volumio[1113]: info: ControllerMpd::getState Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Dec 24 22:09:13 volumiocentre volumio[1113]: info: sendMpdCommand status took 0 milliseconds Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: ControllerMpd::parseState Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 22:09:13 volumiocentre volumio[1113]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: ControllerMpd::parseTrackInfo Dec 24 22:09:13 volumiocentre volumio[1113]: info: ControllerMpd::pushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::servicePushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:13 volumiocentre volumio[1113]: 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":"MONO INC. - Abendrot (Official Video)","artist":"MONO INC.","album":"YouTube","uri":"https://rr5---sn-4g5lznes.googlevideo.com/videoplayback?expire=1766635715&ei=Y2RMaYb3BKbHi9oP0sbJuQs&ip=92.208.190.172&id=o-AEPfLtkgfnurxrFrTwWzeYddxYmVzUT3-D_ZCweOFmDQ&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1766614115%2C&mh=7k&mm=31%2C26&mn=sn-4g5lznes%2Csn-5hne6nzk&ms=au%2Conr&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=4541250&bui=AYUSA3DNJ2pm_jzr0v8Pw2hHfu-vEAOXJ46C2alvFTQjE701VZXp8s86u5HebCGPPBrH7adRNdI5glJW&spc=wH4Qq7hRwfwB3nvxTUDV7jfcnNKsVC9IHG1XGllTKR3KzP3QGfKv9A&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=i1U8yKArzhxjM0ix4yc-wsUR&rqh=1&gir=yes&clen=3395372&dur=209.745&lmt=1752855406676090&mt=1766613675&fvip=3&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=-1EYmC9IX5RZbA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgDj3Oz4ab0Mq-uQlNYXAHgX1cl2ucDO81i8teobX8EpoCICygOYC6HJfxdw-u5IY_-idn03lR8rkyrLMjFyjuLStM&sig=AJfQdSswRgIhAIdZ1hQF0XEm89b-6xcDP9oW8lVxB0HjiukOxOyAYN0YAiEA3gA1Dq36vCGUaCz_JhCCkORoJgiYL41ZS4odzEGGZi8%3D&pot=MlWnpG9EwFZ9JaeacNFymgvQg2rXnR9aLibyaG2SCDFbuRj5DIkqwKVHLBJx5NDfSrWvseFQRGBLh1M_Mi2dFhDOaAc5KsxEToOxj7SaRch1ek-2TxJP&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 24 22:09:13 volumiocentre volumio[1113]: verbose: CURRENT POSITION 0 Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::syncState stateService stop Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: No code Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:13 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:13 volumiocentre sudo[16814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 24 22:09:13 volumiocentre volumio[1113]: info: ------------------------------ 4ms Dec 24 22:09:13 volumiocentre sudo[16814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Volumio is playing Dec 24 22:09:13 volumiocentre volumio[1113]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Volumio is playing Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Volumio is playing Dec 24 22:09:13 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 24 22:09:13 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 24 22:09:13 volumiocentre systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 24 22:09:13 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 24 22:09:13 volumiocentre sudo[16817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 24 22:09:13 volumiocentre sudo[16817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:13 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:13 volumiocentre sudo[16819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 24 22:09:13 volumiocentre sudo[16819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:13 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:13 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:13 volumiocentre sudo[16821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Clipping Monitor started Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Clipping Monitor started Dec 24 22:09:13 volumiocentre volumio[1113]: info: FusionDsp - Clipping Monitor started Dec 24 22:09:13 volumiocentre sudo[16821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:13 volumiocentre sudo[16814]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:13 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Dec 24 22:09:13 volumiocentre volumio[1113]: Job for peppymeterbasic.service canceled. Dec 24 22:09:13 volumiocentre systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 24 22:09:13 volumiocentre systemd[1]: peppymeterbasic.service: Consumed 1.315s CPU time. Dec 24 22:09:13 volumiocentre systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 24 22:09:13 volumiocentre sudo[16821]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:13 volumiocentre volumio[1113]: info: peppymeterbasic Daemon Started Dec 24 22:09:13 volumiocentre sudo[16819]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:13 volumiocentre volumio[1113]: info: peppymeterbasic Daemon Started Dec 24 22:09:13 volumiocentre sudo[16817]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:13 volumiocentre volumio[1113]: info: peppymeterbasic Daemon Started Dec 24 22:09:14 volumiocentre volumio[1113]: [YOUTUBEJS][Text]: Unable to find matching run for attachment run. Skipping... { Dec 24 22:09:14 volumiocentre volumio[1113]: attachment_run: { Dec 24 22:09:14 volumiocentre volumio[1113]: startIndex: 17, Dec 24 22:09:14 volumiocentre volumio[1113]: element: { type: [Object], properties: [Object] }, Dec 24 22:09:14 volumiocentre volumio[1113]: alignment: 'ALIGNMENT_VERTICAL_CENTER' Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: input_data: { Dec 24 22:09:14 volumiocentre volumio[1113]: content: 'Jinjer Metal Band und Napalm Records', Dec 24 22:09:14 volumiocentre volumio[1113]: styleRuns: [ [Object], [Object], [Object] ], Dec 24 22:09:14 volumiocentre volumio[1113]: attachmentRuns: [ [Object], [Object] ] Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: parsed_runs: [ { text: 'Jinjer Metal Band und Napalm Records', startIndex: 0 } ] Dec 24 22:09:14 volumiocentre volumio[1113]: } Dec 24 22:09:14 volumiocentre volumio[1113]: [YOUTUBEJS][Text]: Unable to find matching run for attachment run. Skipping... { Dec 24 22:09:14 volumiocentre volumio[1113]: attachment_run: { Dec 24 22:09:14 volumiocentre volumio[1113]: startIndex: 36, Dec 24 22:09:14 volumiocentre volumio[1113]: element: { type: [Object], properties: [Object] }, Dec 24 22:09:14 volumiocentre volumio[1113]: alignment: 'ALIGNMENT_VERTICAL_CENTER' Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: input_data: { Dec 24 22:09:14 volumiocentre volumio[1113]: content: 'Jinjer Metal Band und Napalm Records', Dec 24 22:09:14 volumiocentre volumio[1113]: styleRuns: [ [Object], [Object], [Object] ], Dec 24 22:09:14 volumiocentre volumio[1113]: attachmentRuns: [ [Object], [Object] ] Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: parsed_runs: [ { text: 'Jinjer Metal Band und Napalm Records', startIndex: 0 } ] Dec 24 22:09:14 volumiocentre volumio[1113]: } Dec 24 22:09:14 volumiocentre volumio[1113]: [YOUTUBEJS][Text]: Unable to find matching run for attachment run. Skipping... { Dec 24 22:09:14 volumiocentre volumio[1113]: attachment_run: { Dec 24 22:09:14 volumiocentre volumio[1113]: startIndex: 21, Dec 24 22:09:14 volumiocentre volumio[1113]: element: { type: [Object], properties: [Object] }, Dec 24 22:09:14 volumiocentre volumio[1113]: alignment: 'ALIGNMENT_VERTICAL_CENTER' Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: input_data: { Dec 24 22:09:14 volumiocentre volumio[1113]: content: 'Nuclear Blast Records und Beast In Black', Dec 24 22:09:14 volumiocentre volumio[1113]: styleRuns: [ [Object], [Object], [Object] ], Dec 24 22:09:14 volumiocentre volumio[1113]: attachmentRuns: [ [Object], [Object] ] Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: parsed_runs: [ Dec 24 22:09:14 volumiocentre volumio[1113]: { text: 'Nuclear Blast Records und Beast In Black', startIndex: 0 } Dec 24 22:09:14 volumiocentre volumio[1113]: ] Dec 24 22:09:14 volumiocentre volumio[1113]: } Dec 24 22:09:14 volumiocentre volumio[1113]: [YOUTUBEJS][Text]: Unable to find matching run for attachment run. Skipping... { Dec 24 22:09:14 volumiocentre volumio[1113]: attachment_run: { Dec 24 22:09:14 volumiocentre volumio[1113]: startIndex: 40, Dec 24 22:09:14 volumiocentre volumio[1113]: element: { type: [Object], properties: [Object] }, Dec 24 22:09:14 volumiocentre volumio[1113]: alignment: 'ALIGNMENT_VERTICAL_CENTER' Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: input_data: { Dec 24 22:09:14 volumiocentre volumio[1113]: content: 'Nuclear Blast Records und Beast In Black', Dec 24 22:09:14 volumiocentre volumio[1113]: styleRuns: [ [Object], [Object], [Object] ], Dec 24 22:09:14 volumiocentre volumio[1113]: attachmentRuns: [ [Object], [Object] ] Dec 24 22:09:14 volumiocentre volumio[1113]: }, Dec 24 22:09:14 volumiocentre volumio[1113]: parsed_runs: [ Dec 24 22:09:14 volumiocentre volumio[1113]: { text: 'Nuclear Blast Records und Beast In Black', startIndex: 0 } Dec 24 22:09:14 volumiocentre volumio[1113]: ] Dec 24 22:09:14 volumiocentre volumio[1113]: } Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushQueue Dec 24 22:09:14 volumiocentre volumio[1113]: info: CorePlayQueue::saveQueue Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::updateTrackBlock Dec 24 22:09:14 volumiocentre volumio[1113]: info: CorePlayQueue::getTrackBlock Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPlay Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::play index 0 Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::stop Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::play index undefined Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 22:09:14 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:14 volumiocentre volumio[1113]: info: CoreStateMachine::startPlaybackTimer Dec 24 22:09:14 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:14 volumiocentre volumio[1113]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22MONO%20INC.%20-%20In%20My%20Darkness%20(Official%20Video)%22%2C%22artist%22%3A%22MONO%20INC.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FzOpq-16Zi5Y%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDUQEvW49eMGa3nPUFGqTLk55llPw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Dec 24 22:09:14 volumiocentre volumio[1113]: info: [ytmusic] Obtained PO token for video #zOpq-16Zi5Y: MlWNVdqUY1P1SY1rxQHRn4O8qZtiTbxfpkrYmdhCqzTT1TIIuVmJxS0rBuPENHPawtmFQFSA52XD63nOh_0-E5iiQvaM-m9Bxu9JqveE3uq94w6rTvkI Dec 24 22:09:14 volumiocentre volumio[1113]: info: [ytmusic] Obtained PO token for video #zOpq-16Zi5Y: MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p Dec 24 22:09:14 volumiocentre volumio[1113]: info: [youtube2] VideoModel.getInfo(zOpq-16Zi5Y): validating stream URL "https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635754&ei=imRMabyQMte9zPsP7aDPqAY&ip=92.208.190.172&id=o-AGi_B7LpZXDvDGiFqQfr5dn1DEgYcnTVWHm3D3lG-Vwl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=154&met=1766614154%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3CkRj5x-SswwfRN18Wk7uE76wPOZdUIVPSQi2QoJ8daAdvwugEjs9CLk58qmb8GiLshi8egbI-a&spc=wH4Qq4eyT7z4ty1y5_zxv_FbsI-eTRiWnDgnnYtfyTqfJpkzoev_uQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yf_P9Y8xYhaLYFPlDnl79MwR&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=_s14yh8J6JFBSQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge9x6iZifNaX32YsPvVLNbXP0KgNO8ZY5QV5db5_BTxECIQC3iHZKqeXgIc7_mjtfxXksGe4pvRo9KHWdI0iQ9Ar7tw%3D%3D&sig=AJfQdSswRgIhANNmpCZxK-0U4gC5CQFDZxixijKMiqRqYRUFabYpmCT5AiEAibKZbaO9X0osnT49v92z88WAWXuWlHU0rbGvGpQnVrE%3D&pot=MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p&cver=1.20250219.01.00"... Dec 24 22:09:15 volumiocentre volumio[1113]: info: [youtube2] VideoModel.getInfo(zOpq-16Zi5Y): stream validated in 0.079s. Dec 24 22:09:15 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Added volumioStateListener Dec 24 22:09:15 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Enabled Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand stop took 1 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 22:09:15 volumiocentre volumio[1113]: info: Dec 24 22:09:15 volumiocentre volumio[1113]: ---------------------------- MPD announces system playlist update Dec 24 22:09:15 volumiocentre volumio[1113]: info: Ignoring MPD Status Update Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand clear took 0 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635754&ei=imRMabyQMte9zPsP7aDPqAY&ip=92.208.190.172&id=o-AGi_B7LpZXDvDGiFqQfr5dn1DEgYcnTVWHm3D3lG-Vwl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=154&met=1766614154%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3CkRj5x-SswwfRN18Wk7uE76wPOZdUIVPSQi2QoJ8daAdvwugEjs9CLk58qmb8GiLshi8egbI-a&spc=wH4Qq4eyT7z4ty1y5_zxv_FbsI-eTRiWnDgnnYtfyTqfJpkzoev_uQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yf_P9Y8xYhaLYFPlDnl79MwR&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=_s14yh8J6JFBSQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge9x6iZifNaX32YsPvVLNbXP0KgNO8ZY5QV5db5_BTxECIQC3iHZKqeXgIc7_mjtfxXksGe4pvRo9KHWdI0iQ9Ar7tw%3D%3D&sig=AJfQdSswRgIhANNmpCZxK-0U4gC5CQFDZxixijKMiqRqYRUFabYpmCT5AiEAibKZbaO9X0osnT49v92z88WAWXuWlHU0rbGvGpQnVrE%3D&pot=MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p&cver=1.20250219.01.00&t.YouTube" Dec 24 22:09:15 volumiocentre volumio[1113]: error: updateQueue error: null Dec 24 22:09:15 volumiocentre volumio[1113]: info: ------------------------------ 0ms Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand addid "https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635754&ei=imRMabyQMte9zPsP7aDPqAY&ip=92.208.190.172&id=o-AGi_B7LpZXDvDGiFqQfr5dn1DEgYcnTVWHm3D3lG-Vwl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=154&met=1766614154%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3CkRj5x-SswwfRN18Wk7uE76wPOZdUIVPSQi2QoJ8daAdvwugEjs9CLk58qmb8GiLshi8egbI-a&spc=wH4Qq4eyT7z4ty1y5_zxv_FbsI-eTRiWnDgnnYtfyTqfJpkzoev_uQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yf_P9Y8xYhaLYFPlDnl79MwR&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=_s14yh8J6JFBSQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge9x6iZifNaX32YsPvVLNbXP0KgNO8ZY5QV5db5_BTxECIQC3iHZKqeXgIc7_mjtfxXksGe4pvRo9KHWdI0iQ9Ar7tw%3D%3D&sig=AJfQdSswRgIhANNmpCZxK-0U4gC5CQFDZxixijKMiqRqYRUFabYpmCT5AiEAibKZbaO9X0osnT49v92z88WAWXuWlHU0rbGvGpQnVrE%3D&pot=MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p&cver=1.20250219.01.00&t.YouTube" took 0 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: MPD COMMAND [object Object] Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: MPD COMMAND [object Object] Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: MPD COMMAND [object Object] Dec 24 22:09:15 volumiocentre volumio[1113]: info: Dec 24 22:09:15 volumiocentre volumio[1113]: ---------------------------- MPD announces system playlist update Dec 24 22:09:15 volumiocentre volumio[1113]: info: Ignoring MPD Status Update Dec 24 22:09:15 volumiocentre volumio[1113]: info: Dec 24 22:09:15 volumiocentre volumio[1113]: ---------------------------- MPD announces system playlist update Dec 24 22:09:15 volumiocentre volumio[1113]: info: Ignoring MPD Status Update Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Dec 24 22:09:15 volumiocentre volumio[1113]: info: ------------------------------ 0ms Dec 24 22:09:15 volumiocentre volumio[1113]: info: ------------------------------ 0ms Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand play took 0 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: info: Dec 24 22:09:15 volumiocentre volumio[1113]: ---------------------------- MPD announces state update: player Dec 24 22:09:15 volumiocentre volumio[1113]: info: ControllerMpd::getState Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 24 22:09:15 volumiocentre volumio[1113]: info: camilladsp stopping service pid 16855... Dec 24 22:09:15 volumiocentre volumio[1113]: grep: /proc/16855/cmdline: binary file matches Dec 24 22:09:15 volumiocentre volumio[1113]: info: camilladsp service terminated, instance 1 Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Effects disabled Dec 24 22:09:15 volumiocentre volumio[1113]: info: camilladsp service started and running in background, instance 1 Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Effects disabled Dec 24 22:09:15 volumiocentre volumio[1113]: info: Dec 24 22:09:15 volumiocentre volumio[1113]: ---------------------------- MPD announces state update: player Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand status took 110 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: info: ControllerMpd::getState Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::parseState Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand status took 0 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::parseState Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::parseTrackInfo Dec 24 22:09:15 volumiocentre volumio[1113]: info: ControllerMpd::pushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::servicePushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MONO INC. - In My Darkness (Official Video)","artist":"MONO INC.","album":"YouTube","uri":"https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635754&ei=imRMabyQMte9zPsP7aDPqAY&ip=92.208.190.172&id=o-AGi_B7LpZXDvDGiFqQfr5dn1DEgYcnTVWHm3D3lG-Vwl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=154&met=1766614154%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3CkRj5x-SswwfRN18Wk7uE76wPOZdUIVPSQi2QoJ8daAdvwugEjs9CLk58qmb8GiLshi8egbI-a&spc=wH4Qq4eyT7z4ty1y5_zxv_FbsI-eTRiWnDgnnYtfyTqfJpkzoev_uQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yf_P9Y8xYhaLYFPlDnl79MwR&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=_s14yh8J6JFBSQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge9x6iZifNaX32YsPvVLNbXP0KgNO8ZY5QV5db5_BTxECIQC3iHZKqeXgIc7_mjtfxXksGe4pvRo9KHWdI0iQ9Ar7tw%3D%3D&sig=AJfQdSswRgIhANNmpCZxK-0U4gC5CQFDZxixijKMiqRqYRUFabYpmCT5AiEAibKZbaO9X0osnT49v92z88WAWXuWlHU0rbGvGpQnVrE%3D&pot=MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: CURRENT POSITION 0 Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::syncState stateService play Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Dec 24 22:09:15 volumiocentre volumio[1113]: info: ------------------------------ 113ms Dec 24 22:09:15 volumiocentre volumio[1113]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 24 22:09:15 volumiocentre volumio[1113]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: ControllerMpd::parseTrackInfo Dec 24 22:09:15 volumiocentre volumio[1113]: info: ControllerMpd::pushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::servicePushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CorePlayQueue::getTrack 0 Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MONO INC. - In My Darkness (Official Video)","artist":"MONO INC.","album":"YouTube","uri":"https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635754&ei=imRMabyQMte9zPsP7aDPqAY&ip=92.208.190.172&id=o-AGi_B7LpZXDvDGiFqQfr5dn1DEgYcnTVWHm3D3lG-Vwl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=154&met=1766614154%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3CkRj5x-SswwfRN18Wk7uE76wPOZdUIVPSQi2QoJ8daAdvwugEjs9CLk58qmb8GiLshi8egbI-a&spc=wH4Qq4eyT7z4ty1y5_zxv_FbsI-eTRiWnDgnnYtfyTqfJpkzoev_uQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yf_P9Y8xYhaLYFPlDnl79MwR&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=_s14yh8J6JFBSQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge9x6iZifNaX32YsPvVLNbXP0KgNO8ZY5QV5db5_BTxECIQC3iHZKqeXgIc7_mjtfxXksGe4pvRo9KHWdI0iQ9Ar7tw%3D%3D&sig=AJfQdSswRgIhANNmpCZxK-0U4gC5CQFDZxixijKMiqRqYRUFabYpmCT5AiEAibKZbaO9X0osnT49v92z88WAWXuWlHU0rbGvGpQnVrE%3D&pot=MlWRnLthLiMiKJGipPSc71TdtVIDuPEvcSvEULm35kQEtC7B2KzEtfpKGiqlwT6qFbiZiTV1qhUUimUH5ghzY0_DXj_tDyIxEY4181pDjMcqOn53oq6p&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 24 22:09:15 volumiocentre volumio[1113]: verbose: CURRENT POSITION 0 Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::syncState stateService play Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::syncState currentStatus play Dec 24 22:09:15 volumiocentre volumio[1113]: info: Received an update from plugin. extracting info from payload Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: [youtube2] (AutoplayManager) Added mpdStateListener Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreStateMachine::pushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioPushState Dec 24 22:09:15 volumiocentre volumio[1113]: info: ------------------------------ 9ms Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Volumio is playing Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Volumio is playing Dec 24 22:09:15 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 24 22:09:15 volumiocentre volumio[1113]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 24 22:09:15 volumiocentre sudo[16882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 24 22:09:15 volumiocentre sudo[16882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:15 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:15 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:15 volumiocentre sudo[16885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Clipping Monitor started Dec 24 22:09:15 volumiocentre sudo[16885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:15 volumiocentre volumio[1113]: info: FusionDsp - Clipping Monitor started Dec 24 22:09:15 volumiocentre sudo[16882]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:15 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 22:09:15 volumiocentre volumio[1113]: info: [now-playing] Fetch metadata (youtube2 plugin): {"type":"song","name":"MONO INC. - In My Darkness (Official Video)","artist":"MONO INC.","album":"YouTube","duration":"206","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22MONO%20INC.%20-%20In%20My%20Darkness%20(Official%20Video)%22%2C%22artist%22%3A%22MONO%20INC.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FzOpq-16Zi5Y%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDUQEvW49eMGa3nPUFGqTLk55llPw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D","service":"youtube2"} Dec 24 22:09:15 volumiocentre volumio[1113]: info: [youtube2] Fetch song info for Now Playing plugin. URI: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22MONO%20INC.%20-%20In%20My%20Darkness%20(Official%20Video)%22%2C%22artist%22%3A%22MONO%20INC.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FzOpq-16Zi5Y%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDUQEvW49eMGa3nPUFGqTLk55llPw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Dec 24 22:09:15 volumiocentre volumio[1113]: info: peppymeterbasic Daemon Started Dec 24 22:09:15 volumiocentre volumio[1113]: info: [ytmusic] Obtained PO token for video #zOpq-16Zi5Y: MlXwt3Q90sTOTvCJa6hgCLi71HnM5A3InU2le3brGqPo0k_qF_A4UhYsewFqncJN-d74ovopVvL47AQsKVSPhKOlPxQiU97W_ehZC4QjJSZJhtJHat7y Dec 24 22:09:15 volumiocentre sudo[16885]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:15 volumiocentre volumio[1113]: info: peppymeterbasic Daemon Started Dec 24 22:09:15 volumiocentre volumio[1113]: info: [ytmusic] Obtained PO token for video #zOpq-16Zi5Y: MlXJ5z1S-kwVT8nZIsdIgGO67SmFiyVARkycKz-EMisz03a6Xp8Q2s0tQlEj8urFIt_B8rNGfnoj7T18YDunDHikBkRrPPZeJuk8bO3G9xnJywgLg1k3 Dec 24 22:09:16 volumiocentre volumio[1113]: info: [youtube2] VideoModel.getInfo(zOpq-16Zi5Y): validating stream URL "https://rr2---sn-4g5e6nsy.googlevideo.com/videoplayback?expire=1766635755&ei=i2RMaYGUOauAsvQPyubr2Q8&ip=92.208.190.172&id=o-ABq5qggd7ZIOfOgfaj9ZbFdwPzTCqBAR6Hf5SvN68FbT&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=105&met=1766614155%2C&mh=9n&mm=31%2C26&mn=sn-4g5e6nsy%2Csn-5hneknee&ms=au%2Conr&mv=m&mvi=2&pl=18&rms=au%2Cau&initcwndbps=2968750&bui=AYUSA3AkIo4sIrNUvivFJlNDQOSdIFBwd-HqEAMFLeEzJ2gbqEW4D-86vj0BnT2XrLID9kF0yBjxJcDn&spc=wH4QqxCu9k42lKZQgTq3V1wr3LyAiy6tUEPFUX_Llrwtvfgh8-d4Ig&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5sX51YIT1BLKUkEv--JKUlER&rqh=1&gir=yes&clen=3352045&dur=206.441&lmt=1743026450164678&mt=1766613675&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=WZEavR03_GLZlg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgHG6xwRf6t0yK57ROlz1vxavUGx1L0Cuk4bfYkCychYACIF6J2FBxlz7IT_wgnbpzXtrbkXcAsJBJjAx1WzsBQrRr&sig=AJfQdSswRgIhAKKbpNU9ViX11fhSX7eWIDFFrtqxnS3W6FdZ8bYE-YF2AiEAs3xQ48276-PMhZMRq32P7RpUH6CVyQ5HejOIC0V1p6I%3D&pot=MlXJ5z1S-kwVT8nZIsdIgGO67SmFiyVARkycKz-EMisz03a6Xp8Q2s0tQlEj8urFIt_B8rNGfnoj7T18YDunDHikBkRrPPZeJuk8bO3G9xnJywgLg1k3&cver=1.20250219.01.00"... Dec 24 22:09:16 volumiocentre volumio[1113]: info: [youtube2] VideoModel.getInfo(zOpq-16Zi5Y): stream validated in 0.083s. Dec 24 22:09:16 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 22:09:16 volumiocentre volumio[1113]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"MONO INC. - In My Darkness (Official Video)","artist":"MONO INC.","album":"YouTube","duration":"206","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22MONO%20INC.%20-%20In%20My%20Darkness%20(Official%20Video)%22%2C%22artist%22%3A%22MONO%20INC.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FzOpq-16Zi5Y%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDUQEvW49eMGa3nPUFGqTLk55llPw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22zOpq-16Zi5Y%22%2C%22playlistId%22%3A%22RDEMAsvgKLQmFaDpU_R6q8gvjw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D","service":""} Dec 24 22:09:16 volumiocentre volumio[1113]: info: [now-playing] LRCLIB getLyrics(): song: "MONO INC. - In My Darkness (Official Video)" album: "YouTube" artist: "MONO INC." duration: "206" Dec 24 22:09:16 volumiocentre volumio[1113]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=MONO+INC.+-+In+My+Darkness+%28Official+Video%29&artist_name=MONO+INC.&album_name=YouTube&duration=206 Dec 24 22:09:20 volumiocentre volumio[1113]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=MONO+INC.+-+In+My+Darkness+%28Official+Video%29&artist_name=MONO+INC.&album_name=YouTube&duration=206): Error: Response error: 404 - Not Found Dec 24 22:09:20 volumiocentre volumio[1113]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:20:15) Dec 24 22:09:20 volumiocentre volumio[1113]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 24 22:09:20 volumiocentre volumio[1113]: at async LRCLibAPI.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:33:24) Dec 24 22:09:20 volumiocentre volumio[1113]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Dec 24 22:09:20 volumiocentre volumio[1113]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:132:40 Dec 24 22:09:20 volumiocentre volumio[1113]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Dec 24 22:09:35 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 22:09:35 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 24 22:09:40 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:40Z" level=trace msg="sent dealer ping" Dec 24 22:09:40 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:40Z" level=trace msg="received dealer pong" Dec 24 22:09:47 volumiocentre go-librespot[2034]: time="2025-12-24T22:09:47Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 24 22:09:47 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 24 22:09:47 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 22:09:47 volumiocentre volumio[1113]: Cannot find translation for source YouTube2 Dec 24 22:09:47 volumiocentre volumio[1113]: Cannot find translation for source Randomizer Dec 24 22:09:47 volumiocentre volumio[1113]: info: Disabling plugin spop Dec 24 22:09:47 volumiocentre volumio[1113]: info: Done. Dec 24 22:09:47 volumiocentre volumio[1113]: info: Connection to go-librespot Websocket closed Dec 24 22:09:47 volumiocentre sudo[16975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Dec 24 22:09:47 volumiocentre sudo[16975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:47 volumiocentre systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 24 22:09:47 volumiocentre systemd[1]: go-librespot-daemon.service: Killing process 2044 (go-librespot) with signal SIGKILL. Dec 24 22:09:47 volumiocentre systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 24 22:09:47 volumiocentre systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 24 22:09:47 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:47 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:47 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:47 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:47 volumiocentre sudo[16975]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:50 volumiocentre volumio[1113]: info: Enabling plugin spop Dec 24 22:09:50 volumiocentre volumio[1113]: info: Loading plugin "spop"... Dec 24 22:09:50 volumiocentre volumio[1113]: info: PLUGIN START: spop Dec 24 22:09:50 volumiocentre volumio[1113]: info: Creating Spotify config file Dec 24 22:09:50 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 22:09:50 volumiocentre volumio[1113]: info: Done. Dec 24 22:09:50 volumiocentre volumio[1113]: info: Spotify config file written Dec 24 22:09:50 volumiocentre volumio[1113]: info: No need to fix Spotify hosts Dec 24 22:09:50 volumiocentre sudo[16981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 24 22:09:50 volumiocentre sudo[16981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 22:09:50 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:50 volumiocentre systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 24 22:09:50 volumiocentre systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 24 22:09:50 volumiocentre go-librespot[16983]: go-librespot daemon starting... Dec 24 22:09:50 volumiocentre sudo[16981]: pam_unix(sudo:session): session closed for user root Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=info msg="running go-librespot 0.4.0" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="app state loaded" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 24 22:09:50 volumiocentre volumio[1113]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 24 22:09:50 volumiocentre volumio[1113]: SPOTIFY: BQAwgaXig3MOYwPzBnc_ZQMcyJABcZdIU8YtObyUmnBEGLm0EAv7_2ui9Lcn6uhgm6yeNNoUnLtnxIj1x8MlRHB_0dEEz039vwPe2Nts8tZavoKT2OsQ0nA2nQwI5dstRREbF3SwL2Odpw-eXWQnwKti9e9Nvv20msxft9eGO17IRb8IUUQvwJ7wQK2_7IOp2f1O_6Ks_dfi3jads9i_EUZWDnrIgqHodFLzMpcXeYum3d1VplYE9ITvDeLjY3HZOzX0PBUNfDmzxLbsD3Ye2d2_VlaruV1mOfSaI17X46DqI4dLvKVNlrr3 Dec 24 22:09:50 volumiocentre volumio[1113]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 24 22:09:50 volumiocentre volumio[1113]: info: New Spotify access token = BQAwgaXig3MOYwPzBnc_ZQMcyJABcZdIU8YtObyUmnBEGLm0EAv7_2ui9Lcn6uhgm6yeNNoUnLtnxIj1x8MlRHB_0dEEz039vwPe2Nts8tZavoKT2OsQ0nA2nQwI5dstRREbF3SwL2Odpw-eXWQnwKti9e9Nvv20msxft9eGO17IRb8IUUQvwJ7wQK2_7IOp2f1O_6Ks_dfi3jads9i_EUZWDnrIgqHodFLzMpcXeYum3d1VplYE9ITvDeLjY3HZOzX0PBUNfDmzxLbsD3Ye2d2_VlaruV1mOfSaI17X46DqI4dLvKVNlrr3 Dec 24 22:09:50 volumiocentre volumio[1113]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=info msg="zeroconf server listening on port 39899" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="obtained new client token: AADEWiLMJ20qYQrb4H8NJIritNUldF841UoOwz0oTVKOOZZXMU4NkI7ZLmSuVrhUFstR3XRdmNXe7/fMKXt8k5Z7NCoSSoM5tcO/aF6k7rB4aoxhTAvFbGL8dqpfHu89aGq8kT1fsaAmQTQ81RmZxrZhGs/KNkoV8JIWVZSJSEkdXvwv/4+BbsbghXxzXAjxDETmw3ICLyD0f/gc2YP07kqh17NoKdFYWf0OPO5fkUxEkcBbrij87RbQTHA=" Dec 24 22:09:50 volumiocentre volumio[1113]: SPOTIFY: User informations: {"country":"DE","display_name":"Kitcarschaefer","email":"kitcarschaefer@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/kdi4kmx8lxxtihha2pha9f1u9"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/kdi4kmx8lxxtihha2pha9f1u9","id":"kdi4kmx8lxxtihha2pha9f1u9","images":[],"product":"premium","type":"user","uri":"spotify:user:kdi4kmx8lxxtihha2pha9f1u9"} Dec 24 22:09:50 volumiocentre volumio[1113]: info: Spotify Successfully logged in Dec 24 22:09:50 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 24 22:09:50 volumiocentre volumio[1113]: info: [1766614190868] CoreMusicLibrary::Adding element Spotify Dec 24 22:09:50 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 22:09:50 volumiocentre volumio[1113]: Cannot find translation for source YouTube2 Dec 24 22:09:50 volumiocentre volumio[1113]: Cannot find translation for source Randomizer Dec 24 22:09:50 volumiocentre volumio[1113]: Cannot find translation for source Spotify Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="completed keyexchange" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=debug msg="completed challenge" Dec 24 22:09:50 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:50Z" level=info msg="authenticated AP" username="kd*********************u9" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=info msg="authenticated Login5" username="kd*********************u9" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="initializing zeroconf session" username="kd*********************u9" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="dealer connection opened" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=trace msg="starting accesspoint recv loop" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=trace msg="starting dealer recv loop" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=trace msg="received accesspoint ping" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="received connection id: ZDYzMzc0NDAtMWFh...NUUxMTQ3NzBDNg==" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=trace msg="received accesspoint pong ack" Dec 24 22:09:51 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:51Z" level=debug msg="put connect state because NEW_DEVICE" Dec 24 22:09:53 volumiocentre volumio[1113]: info: go-librespot daemon successfully initialized Dec 24 22:09:56 volumiocentre volumio[1113]: info: Initializing connection to go-librespot Websocket Dec 24 22:09:56 volumiocentre go-librespot[16984]: time="2025-12-24T22:09:56Z" level=debug msg="new websocket client" Dec 24 22:09:56 volumiocentre volumio[1113]: info: Connection to go-librespot Websocket established Dec 24 22:09:59 volumiocentre volumio[1113]: info: Getting Spotify volume Dec 24 22:09:59 volumiocentre volumio[1113]: info: Spotify volume: 100 Dec 24 22:09:59 volumiocentre volumio[1113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 24 22:09:59 volumiocentre volumio[1113]: info: CoreCommandRouter::volumioGetState Dec 24 22:09:59 volumiocentre volumio[1113]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Dec 24 22:09:59 volumiocentre volumio[1113]: SPOTIFY: SPOTIFY VOLUME 100 Dec 24 22:09:59 volumiocentre volumio[1113]: SPOTIFY: VOLUMIO VOLUME 26 Dec 24 22:09:59 volumiocentre volumio[1113]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 24 22:09:59 volumiocentre volumio[1113]: info: Setting Spotify Volume from Volumio: 26 Dec 24 22:10:01 volumiocentre volumio[1113]: SPOTIFY: SETTING SPOTIFY VOLUME 26 Dec 24 22:10:01 volumiocentre volumio[1113]: info: Sending Spotify command with payload to local API: /player/volume Dec 24 22:10:01 volumiocentre go-librespot[16984]: time="2025-12-24T22:10:01Z" level=debug msg="update volume requested to 17039/65535" Dec 24 22:10:01 volumiocentre go-librespot[16984]: time="2025-12-24T22:10:01Z" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 22:10:01 volumiocentre go-librespot[16984]: time="2025-12-24T22:10:01Z" level=trace msg="emitting websocket event: volume" Dec 24 22:10:01 volumiocentre volumio[1113]: SPOTIFY: received: {"type":"volume","data":{"value":26,"max":100}} Dec 24 22:10:01 volumiocentre volumio[1113]: SPOTIFY: RECEIVED SPOTIFY VOLUME 26 Dec 24 22:10:10 volumiocentre volumio[1113]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 22:10:21 volumiocentre go-librespot[16984]: time="2025-12-24T22:10:21Z" level=trace msg="sent dealer ping" Dec 24 22:10:21 volumiocentre go-librespot[16984]: time="2025-12-24T22:10:21Z" level=trace msg="received dealer pong" Dec 24 22:10:29 volumiocentre volumio[1113]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 24 22:10:29 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 24 22:10:29 volumiocentre volumio[1113]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 24 22:10:29 volumiocentre volumio[1113]: --2025-12-24 22:10:29-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 24 22:10:29 volumiocentre volumio[1113]: Resolving github.com (github.com)... 140.82.121.3 Dec 24 22:10:29 volumiocentre volumio[1113]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 24 22:10:29 volumiocentre volumio[1113]: HTTP request sent, awaiting response... 302 Found Dec 24 22:10:29 volumiocentre volumio[1113]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 24 22:10:29 volumiocentre volumio[1113]: --2025-12-24 22:10:29-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 24 22:10:29 volumiocentre volumio[1113]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.108.133, 185.199.109.133, ... Dec 24 22:10:29 volumiocentre volumio[1113]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Dec 24 22:10:29 volumiocentre volumio[1113]: HTTP request sent, awaiting response... 200 OK Dec 24 22:10:29 volumiocentre volumio[1113]: Length: 362 [text/plain] Dec 24 22:10:29 volumiocentre volumio[1113]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 24 22:10:29 volumiocentre volumio[1113]: 0K 100% 30.0M=0s Dec 24 22:10:29 volumiocentre volumio[1113]: 2025-12-24 22:10:29 (30.0 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 24 22:10:29 volumiocentre volumio[1113]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 22:10:29 volumiocentre volumio[1113]: info: CoreCommandRouter::Close All Modals sent Dec 24 22:10:32 volumiocentre volumio[1113]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 24 22:10:32 volumiocentre volumio[1113]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 24 22:10:33 volumiocentre volumio[1113]: --2025-12-24 22:10:32-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 24 22:10:33 volumiocentre volumio[1113]: Resolving github.com (github.com)... 140.82.121.3 Dec 24 22:10:33 volumiocentre volumio[1113]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 24 22:10:33 volumiocentre volumio[1113]: HTTP request sent, awaiting response... 404 Not Found Dec 24 22:10:33 volumiocentre volumio[1113]: 2025-12-24 22:10:32 ERROR 404: Not Found. Dec 24 22:10:33 volumiocentre volumio[1113]: --2025-12-24 22:10:32-- http://a/ Dec 24 22:10:33 volumiocentre volumio[1113]: Resolving a (a)... failed: Device or resource busy. Dec 24 22:10:33 volumiocentre volumio[1113]: wget: unable to resolve host address ‘a’ Dec 24 22:10:33 volumiocentre volumio[1113]: --2025-12-24 22:10:32-- http://packet.zip/ Dec 24 22:10:33 volumiocentre volumio[1113]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 24 22:10:33 volumiocentre volumio[1113]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 24 22:10:33 volumiocentre volumio[1113]: HTTP request sent, awaiting response... 302 Found Dec 24 22:10:33 volumiocentre volumio[1113]: Location: http://www.packet.zip/ [following] Dec 24 22:10:33 volumiocentre volumio[1113]: --2025-12-24 22:10:32-- http://www.packet.zip/ Dec 24 22:10:33 volumiocentre volumio[1113]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 24 22:10:33 volumiocentre volumio[1113]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 24 22:10:33 volumiocentre volumio[1113]: HTTP request sent, awaiting response... 403 Forbidden Dec 24 22:10:33 volumiocentre volumio[1113]: 2025-12-24 22:10:33 ERROR 403: Forbidden. Dec 24 22:10:33 volumiocentre volumio[1113]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove 'a': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 22:10:33 volumiocentre volumio[1113]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove 'a': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 24 22:10:33 volumiocentre volumio[1113]: at checkExecSyncError (node:child_process:887:11) Dec 24 22:10:33 volumiocentre volumio[1113]: at execSync (node:child_process:959:15) Dec 24 22:10:33 volumiocentre volumio[1113]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Dec 24 22:10:33 volumiocentre volumio[1113]: at new Promise () Dec 24 22:10:33 volumiocentre volumio[1113]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Dec 24 22:10:33 volumiocentre volumio[1113]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 24 22:10:33 volumiocentre volumio[1113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 24 22:10:33 volumiocentre volumio[1113]: at Socket.emit (node:events:514:28) Dec 24 22:10:33 volumiocentre volumio[1113]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 24 22:10:33 volumiocentre volumio[1113]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 24 22:10:33 volumiocentre volumio[1113]: status: 1, Dec 24 22:10:33 volumiocentre volumio[1113]: signal: null, Dec 24 22:10:33 volumiocentre volumio[1113]: output: [ Dec 24 22:10:33 volumiocentre volumio[1113]: null, Dec 24 22:10:33 volumiocentre volumio[1113]: , Dec 24 22:10:33 volumiocentre volumio[1113]: Dec 24 22:10:33 volumiocentre volumio[1113]: ], Dec 24 22:10:33 volumiocentre volumio[1113]: pid: 17109, Dec 24 22:10:33 volumiocentre volumio[1113]: stdout: , Dec 24 22:10:33 volumiocentre volumio[1113]: stderr: Dec 24 22:10:33 volumiocentre volumio[1113]: } Dec 24 22:10:33 volumiocentre volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 22:10:33 volumiocentre sudo[17125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-24 22:09' Dec 24 22:10:33 volumiocentre sudo[17125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"