May 31 04:23:04 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:06 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:06 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:06 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:14 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:16 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:24 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:26 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:26 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:26 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:34 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:36 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:44 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:46 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:46 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:46 volumio-rp3 volumio[1199]: info: Listing playlists May 31 04:23:49 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 76 May 31 04:23:49 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:49 volumio-rp3 volumio[1199]: info: Prefetching next song May 31 04:23:49 volumio-rp3 volumio[1199]: info: [youtube2] Obtained PO token for video #KR4cRCGUSug: MlvaK1jzSlgz4tpvHLtIi4OVcrBPOtPQWTW2RM-YNgkuFJ9OjO-qblkltHK1Yb5yGtXXVlqWytWTSqSzzdoAGOCEq1CMXmQG0AQTgsd1ikRGqnU1H_avwaawEnK2 May 31 04:23:49 volumio-rp3 volumio[1199]: info: [youtube2] Obtained PO token for video #KR4cRCGUSug: MltlEIXwR03QtWVUwbhFnmDCzYuSOd7FumIJfxKbOxzNQyB1Ueyne7pyC0loYrNn-YJobYeVx8BwHRuIENkNDQPTFGtRXWkTM1OsuRp2h1Ha4AxJWT5Cyyi-kRj4 May 31 04:23:49 volumio-rp3 volumio[1199]: warn: [youtube2] Error getting playback info with TV client. The playability status of the target is: { May 31 04:23:49 volumio-rp3 volumio[1199]: "status": "OK", May 31 04:23:49 volumio-rp3 volumio[1199]: "reason": "", May 31 04:23:49 volumio-rp3 volumio[1199]: "embeddable": true, May 31 04:23:49 volumio-rp3 volumio[1199]: "audio_only_playability": { May 31 04:23:49 volumio-rp3 volumio[1199]: "type": "AudioOnlyPlayability", May 31 04:23:49 volumio-rp3 volumio[1199]: "audio_only_availability": "FEATURE_AVAILABILITY_ALLOWED" May 31 04:23:49 volumio-rp3 volumio[1199]: }, May 31 04:23:49 volumio-rp3 volumio[1199]: "error_screen": null May 31 04:23:49 volumio-rp3 volumio[1199]: } May 31 04:23:49 volumio-rp3 volumio[1199]: error: [youtube2] Error in VideoModel.getPlaybackInfo(KR4cRCGUSug): No valid URL to decipher Error: No valid URL to decipher May 31 04:23:49 volumio-rp3 volumio[1199]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34425:13) May 31 04:23:49 volumio-rp3 volumio[1199]: at Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:30057:19) May 31 04:23:49 volumio-rp3 volumio[1199]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:254:45) May 31 04:23:49 volumio-rp3 volumio[1199]: at VideoModel._VideoModel_doGetPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:186:122) May 31 04:23:49 volumio-rp3 volumio[1199]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 04:23:49 volumio-rp3 volumio[1199]: at async VideoModel._VideoModel_doGetPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:152:20) May 31 04:23:49 volumio-rp3 volumio[1199]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:185:19) May 31 04:23:49 volumio-rp3 volumio[1199]: at async PlayController.prefetch (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:253:53) May 31 04:23:49 volumio-rp3 volumio[1199]: error: [youtube2] Error in VideoModel.getPlaybackInfo(KR4cRCGUSug): No valid URL to decipher Error: No valid URL to decipher May 31 04:23:49 volumio-rp3 volumio[1199]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34425:13) May 31 04:23:49 volumio-rp3 volumio[1199]: at Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:30057:19) May 31 04:23:49 volumio-rp3 volumio[1199]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:254:45) May 31 04:23:49 volumio-rp3 volumio[1199]: at VideoModel._VideoModel_doGetPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:186:122) May 31 04:23:49 volumio-rp3 volumio[1199]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 04:23:49 volumio-rp3 volumio[1199]: at async VideoModel._VideoModel_doGetPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:152:20) May 31 04:23:49 volumio-rp3 volumio[1199]: at async PlayController.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:185:19) May 31 04:23:49 volumio-rp3 volumio[1199]: at async PlayController.prefetch (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:253:53) May 31 04:23:49 volumio-rp3 volumio[1199]: error: [youtube2-play] Prefetch failed: Error: No valid URL to decipher May 31 04:23:53 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:23:53 volumio-rp3 volumio[1199]: info: May 31 04:23:53 volumio-rp3 volumio[1199]: ---------------------------- MPD announces state update: player May 31 04:23:53 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:23:53 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 7 milliseconds May 31 04:23:53 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 7 milliseconds May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) MPD 'stop' event received May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) Check if autoplay needed May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) Autoplay not applicable to state: {"random":true,"repeat":true,"repeatSingle":false} May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) Removed mpdStateListener May 31 04:23:53 volumio-rp3 volumio[1199]: info: ControllerMpd::pushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::servicePushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::pushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioPushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) Added mpdStateListener May 31 04:23:53 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 76 May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 04:23:53 volumio-rp3 volumio[1199]: verbose: CURRENT POSITION 76 May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState stateService stop May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState currentStatus play May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::play index undefined May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::pushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioPushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::startPlaybackTimer May 31 04:23:53 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:53 volumio-rp3 volumio[1199]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22KR4cRCGUSug%22%2C%22playlistId%22%3A%22PLXmwQZcfhFf-JW1MjkS5ohZZsukhsc3Lj%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A17%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22My%2010%20year%20old%20niece%20reacts%20to%20%5C%22I%20Am%20Your%20Father%5C%22%20in%20Empire%20Strikes%20Back!%22%2C%22artist%22%3A%22SpectreSoundStudios%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FKR4cRCGUSug%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhlIGMoVDAP%26rs%3DAOn4CLDfUZpodICmBy55CkfUGUqN548Ulg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22KR4cRCGUSug%22%2C%22playlistId%22%3A%22PLXmwQZcfhFf-JW1MjkS5ohZZsukhsc3Lj%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A17%7D%7D%7D May 31 04:23:53 volumio-rp3 volumio[1199]: info: ------------------------------ 34ms May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreStateMachine::pushState May 31 04:23:53 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 04:23:53 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioPushState May 31 04:23:54 volumio-rp3 volumio[1199]: info: [youtube2] Obtained PO token for video #KR4cRCGUSug: Mltvf_jXxIYw5G87vJ_GVYCTx-TvHl0OWjMDEG-8uNctEioaLMsksFojASYVRTCsGdNiAvqyRAuQTBHnbf6OxuOCHgQseurY0wKm1mdRBJqQvjFMz1wJn4oVB-qR May 31 04:23:54 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:54 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:54 volumio-rp3 volumio[1199]: info: [youtube2] Obtained PO token for video #KR4cRCGUSug: Mlsci42F_t4b9xzPyc38DauAtBCaTGdWcSBw5Brugo8GAVnuWZke6HEwctJgFwr0MsAR9o_gflO7X2ITGKy0nsiRbfBZKNCA-BHVIhIDPsIe8u5TqXx9GHXvhade May 31 04:23:54 volumio-rp3 volumio[1199]: info: camilladsp respawn in 100 ms (attempt 1/10) May 31 04:23:54 volumio-rp3 volumio[1199]: warn: [youtube2] Error getting playback info with TV client. The playability status of the target is: { May 31 04:23:54 volumio-rp3 volumio[1199]: "status": "OK", May 31 04:23:54 volumio-rp3 volumio[1199]: "reason": "", May 31 04:23:54 volumio-rp3 volumio[1199]: "embeddable": true, May 31 04:23:54 volumio-rp3 volumio[1199]: "audio_only_playability": { May 31 04:23:54 volumio-rp3 volumio[1199]: "type": "AudioOnlyPlayability", May 31 04:23:54 volumio-rp3 volumio[1199]: "audio_only_availability": "FEATURE_AVAILABILITY_ALLOWED" May 31 04:23:54 volumio-rp3 volumio[1199]: }, May 31 04:23:54 volumio-rp3 volumio[1199]: "error_screen": null May 31 04:23:54 volumio-rp3 volumio[1199]: } May 31 04:23:54 volumio-rp3 volumio[1199]: warn: [youtube2] Got error in VideoModel.getPlaybackInfo(KR4cRCGUSug): No valid URL to decipher May 31 04:23:54 volumio-rp3 volumio[1199]: warn: [youtube2] Going to retry with 'MWEB' client May 31 04:23:54 volumio-rp3 volumio[1199]: info: [youtube2] Obtained PO token for video #KR4cRCGUSug: Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER May 31 04:23:54 volumio-rp3 volumio[1199]: info: [youtube2] Got stream with MWEB client May 31 04:23:54 volumio-rp3 volumio[1199]: info: [youtube2] VideoModel.getPlaybackInfo(KR4cRCGUSug): validating stream URL "https://rr7---sn-ovn-apne.googlevideo.com/videoplayback?expire=1780219434&ei=yqkbaurQJquNoccPyYuPcA&ip=95.93.239.57&id=o-ACpJ7dU3XIjlpe9b0w8CvCXKqD2uhWNJIRfGDN7j1gGx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=164&met=1780197834%2C&mh=wS&mm=31%2C26&mn=sn-ovn-apne%2Csn-h5q7dns7&ms=au%2Conr&mv=m&mvi=7&pl=23&rms=au%2Cau&initcwndbps=2013750&siu=1&bui=AbKmrwozTApIlyWg-XGmL8PQxUvae8lTLd-Wanv0qhaGPEEGbJ_QGk9ktaOVRdK8upg0Wp0Kag&spc=96Xrv1b_mIUAk5T7zbf_jFMH87rloPrfKeronh-nZZaJD1PO6XS3QAECm_c62VzYVh-tSS-Hoeh82SpbE2W9zAOh&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MXBqydOSlK6-E9DID_TCBRQV&rqh=1&gir=yes&clen=1635678&dur=95.161&lmt=1751263100373319&mt=1780197436&fvip=3&keepalive=yes&fexp=51565115%2C51565682&c=MWEB&sefc=1&txp=5532534&n=F8w8shcTd6DxkQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AHEqNM4wRgIhAM-gIjpMoS06dj0YjJz6ZFJgKjmNc0wT3nuyTea_n2j3AiEAjslWvGegEMBMHuVfVMZSx24DaVJujILPewnSN9F9g1M%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMyZpx5mIcsrrchPKUPJGnEVuv7XxO_1zVZ9-kZdBEr5AiBPIJXc-KV9urELkaPn9Ks6ILKplvkhbGacjxgjOkMUiA%3D%3D&cver=2.20260205.04.01&pot=Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER"... May 31 04:23:55 volumio-rp3 volumio[1199]: warn: [youtube2] VideoModel.getPlaybackInfo(KR4cRCGUSug): stream validation failed (403 - Forbidden); retrying after 2s... May 31 04:23:56 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioGetState May 31 04:23:56 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:23:57 volumio-rp3 volumio[1199]: warn: [youtube2] VideoModel.getPlaybackInfo(KR4cRCGUSug): stream validation failed (403 - Forbidden); retrying after 2s... May 31 04:23:59 volumio-rp3 volumio[1199]: info: [youtube2] VideoModel.getPlaybackInfo(KR4cRCGUSug): stream validated in 4.222s. May 31 04:23:59 volumio-rp3 volumio[1199]: info: [youtube2] (AutoplayManager) Enabled May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop May 31 04:23:59 volumio-rp3 volumio[1199]: info: sendMpdCommand stop took 2 milliseconds May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear May 31 04:23:59 volumio-rp3 volumio[1199]: info: May 31 04:23:59 volumio-rp3 volumio[1199]: ---------------------------- MPD announces system playlist update May 31 04:23:59 volumio-rp3 volumio[1199]: info: Ignoring MPD Status Update May 31 04:23:59 volumio-rp3 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand addid "https://rr7---sn-ovn-apne.googlevideo.com/videoplayback?expire=1780219434&ei=yqkbaurQJquNoccPyYuPcA&ip=95.93.239.57&id=o-ACpJ7dU3XIjlpe9b0w8CvCXKqD2uhWNJIRfGDN7j1gGx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=164&met=1780197834%2C&mh=wS&mm=31%2C26&mn=sn-ovn-apne%2Csn-h5q7dns7&ms=au%2Conr&mv=m&mvi=7&pl=23&rms=au%2Cau&initcwndbps=2013750&siu=1&bui=AbKmrwozTApIlyWg-XGmL8PQxUvae8lTLd-Wanv0qhaGPEEGbJ_QGk9ktaOVRdK8upg0Wp0Kag&spc=96Xrv1b_mIUAk5T7zbf_jFMH87rloPrfKeronh-nZZaJD1PO6XS3QAECm_c62VzYVh-tSS-Hoeh82SpbE2W9zAOh&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MXBqydOSlK6-E9DID_TCBRQV&rqh=1&gir=yes&clen=1635678&dur=95.161&lmt=1751263100373319&mt=1780197436&fvip=3&keepalive=yes&fexp=51565115%2C51565682&c=MWEB&sefc=1&txp=5532534&n=F8w8shcTd6DxkQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AHEqNM4wRgIhAM-gIjpMoS06dj0YjJz6ZFJgKjmNc0wT3nuyTea_n2j3AiEAjslWvGegEMBMHuVfVMZSx24DaVJujILPewnSN9F9g1M%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMyZpx5mIcsrrchPKUPJGnEVuv7XxO_1zVZ9-kZdBEr5AiBPIJXc-KV9urELkaPn9Ks6ILKplvkhbGacjxgjOkMUiA%3D%3D&cver=2.20260205.04.01&pot=Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER&t.YouTube" May 31 04:23:59 volumio-rp3 volumio[1199]: error: updateQueue error: null May 31 04:23:59 volumio-rp3 volumio[1199]: info: May 31 04:23:59 volumio-rp3 volumio[1199]: ---------------------------- MPD announces system playlist update May 31 04:23:59 volumio-rp3 volumio[1199]: info: Ignoring MPD Status Update May 31 04:23:59 volumio-rp3 volumio[1199]: info: ------------------------------ 3ms May 31 04:23:59 volumio-rp3 volumio[1199]: info: sendMpdCommand addid "https://rr7---sn-ovn-apne.googlevideo.com/videoplayback?expire=1780219434&ei=yqkbaurQJquNoccPyYuPcA&ip=95.93.239.57&id=o-ACpJ7dU3XIjlpe9b0w8CvCXKqD2uhWNJIRfGDN7j1gGx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=164&met=1780197834%2C&mh=wS&mm=31%2C26&mn=sn-ovn-apne%2Csn-h5q7dns7&ms=au%2Conr&mv=m&mvi=7&pl=23&rms=au%2Cau&initcwndbps=2013750&siu=1&bui=AbKmrwozTApIlyWg-XGmL8PQxUvae8lTLd-Wanv0qhaGPEEGbJ_QGk9ktaOVRdK8upg0Wp0Kag&spc=96Xrv1b_mIUAk5T7zbf_jFMH87rloPrfKeronh-nZZaJD1PO6XS3QAECm_c62VzYVh-tSS-Hoeh82SpbE2W9zAOh&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MXBqydOSlK6-E9DID_TCBRQV&rqh=1&gir=yes&clen=1635678&dur=95.161&lmt=1751263100373319&mt=1780197436&fvip=3&keepalive=yes&fexp=51565115%2C51565682&c=MWEB&sefc=1&txp=5532534&n=F8w8shcTd6DxkQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AHEqNM4wRgIhAM-gIjpMoS06dj0YjJz6ZFJgKjmNc0wT3nuyTea_n2j3AiEAjslWvGegEMBMHuVfVMZSx24DaVJujILPewnSN9F9g1M%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMyZpx5mIcsrrchPKUPJGnEVuv7XxO_1zVZ9-kZdBEr5AiBPIJXc-KV9urELkaPn9Ks6ILKplvkhbGacjxgjOkMUiA%3D%3D&cver=2.20260205.04.01&pot=Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER&t.YouTube" took 3 milliseconds May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: MPD COMMAND [object Object] May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: MPD COMMAND [object Object] May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: MPD COMMAND [object Object] May 31 04:23:59 volumio-rp3 volumio[1199]: info: May 31 04:23:59 volumio-rp3 volumio[1199]: ---------------------------- MPD announces system playlist update May 31 04:23:59 volumio-rp3 volumio[1199]: info: Ignoring MPD Status Update May 31 04:23:59 volumio-rp3 volumio[1199]: info: ------------------------------ 4ms May 31 04:23:59 volumio-rp3 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 04:23:59 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play May 31 04:23:59 volumio-rp3 volumio[1199]: info: ------------------------------ 3ms May 31 04:23:59 volumio-rp3 volumio[1199]: info: sendMpdCommand play took 2 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:24:00 volumio-rp3 volumio[1199]: info: May 31 04:24:00 volumio-rp3 volumio[1199]: ---------------------------- MPD announces state update: player May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:24:00 volumio-rp3 volumio[1199]: info: May 31 04:24:00 volumio-rp3 volumio[1199]: ---------------------------- MPD announces state update: player May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::getState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 4 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 4 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 5 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand status took 5 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseState May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseTrackInfo May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseTrackInfo May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::pushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreCommandRouter::servicePushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":95,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My 10 year old niece reacts to \"I Am Your Father\" in Empire Strikes Back!","artist":"SpectreSoundStudios","album":"YouTube","uri":"https://rr7---sn-ovn-apne.googlevideo.com/videoplayback?expire=1780219434&ei=yqkbaurQJquNoccPyYuPcA&ip=95.93.239.57&id=o-ACpJ7dU3XIjlpe9b0w8CvCXKqD2uhWNJIRfGDN7j1gGx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=164&met=1780197834%2C&mh=wS&mm=31%2C26&mn=sn-ovn-apne%2Csn-h5q7dns7&ms=au%2Conr&mv=m&mvi=7&pl=23&rms=au%2Cau&initcwndbps=2013750&siu=1&bui=AbKmrwozTApIlyWg-XGmL8PQxUvae8lTLd-Wanv0qhaGPEEGbJ_QGk9ktaOVRdK8upg0Wp0Kag&spc=96Xrv1b_mIUAk5T7zbf_jFMH87rloPrfKeronh-nZZaJD1PO6XS3QAECm_c62VzYVh-tSS-Hoeh82SpbE2W9zAOh&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MXBqydOSlK6-E9DID_TCBRQV&rqh=1&gir=yes&clen=1635678&dur=95.161&lmt=1751263100373319&mt=1780197436&fvip=3&keepalive=yes&fexp=51565115%2C51565682&c=MWEB&sefc=1&txp=5532534&n=F8w8shcTd6DxkQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AHEqNM4wRgIhAM-gIjpMoS06dj0YjJz6ZFJgKjmNc0wT3nuyTea_n2j3AiEAjslWvGegEMBMHuVfVMZSx24DaVJujILPewnSN9F9g1M%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMyZpx5mIcsrrchPKUPJGnEVuv7XxO_1zVZ9-kZdBEr5AiBPIJXc-KV9urELkaPn9Ks6ILKplvkhbGacjxgjOkMUiA%3D%3D&cver=2.20260205.04.01&pot=Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER&t.YouTube","trackType":"YouTube"} May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: CURRENT POSITION 17 May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState stateService play May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop May 31 04:24:00 volumio-rp3 volumio[1199]: info: ------------------------------ 14ms May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseTrackInfo May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: ControllerMpd::parseTrackInfo May 31 04:24:00 volumio-rp3 volumio[1199]: info: ControllerMpd::pushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreCommandRouter::servicePushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CorePlayQueue::getTrack 17 May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":95,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My 10 year old niece reacts to \"I Am Your Father\" in Empire Strikes Back!","artist":"SpectreSoundStudios","album":"YouTube","uri":"https://rr7---sn-ovn-apne.googlevideo.com/videoplayback?expire=1780219434&ei=yqkbaurQJquNoccPyYuPcA&ip=95.93.239.57&id=o-ACpJ7dU3XIjlpe9b0w8CvCXKqD2uhWNJIRfGDN7j1gGx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=164&met=1780197834%2C&mh=wS&mm=31%2C26&mn=sn-ovn-apne%2Csn-h5q7dns7&ms=au%2Conr&mv=m&mvi=7&pl=23&rms=au%2Cau&initcwndbps=2013750&siu=1&bui=AbKmrwozTApIlyWg-XGmL8PQxUvae8lTLd-Wanv0qhaGPEEGbJ_QGk9ktaOVRdK8upg0Wp0Kag&spc=96Xrv1b_mIUAk5T7zbf_jFMH87rloPrfKeronh-nZZaJD1PO6XS3QAECm_c62VzYVh-tSS-Hoeh82SpbE2W9zAOh&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MXBqydOSlK6-E9DID_TCBRQV&rqh=1&gir=yes&clen=1635678&dur=95.161&lmt=1751263100373319&mt=1780197436&fvip=3&keepalive=yes&fexp=51565115%2C51565682&c=MWEB&sefc=1&txp=5532534&n=F8w8shcTd6DxkQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AHEqNM4wRgIhAM-gIjpMoS06dj0YjJz6ZFJgKjmNc0wT3nuyTea_n2j3AiEAjslWvGegEMBMHuVfVMZSx24DaVJujILPewnSN9F9g1M%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMyZpx5mIcsrrchPKUPJGnEVuv7XxO_1zVZ9-kZdBEr5AiBPIJXc-KV9urELkaPn9Ks6ILKplvkhbGacjxgjOkMUiA%3D%3D&cver=2.20260205.04.01&pot=Mltm1UiDdk6QzmaRDMt0nSC5zk5fSu_G-hkKut_oCh-NOCOwnJ-WePoJCIylEYJkuflrqErm9sMwZhhN3ao8DkOoF66cLlgQcyivfNcFtlJXDbuMnLj76bZGiuER&t.YouTube","trackType":"YouTube"} May 31 04:24:00 volumio-rp3 volumio[1199]: verbose: CURRENT POSITION 17 May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState stateService play May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::syncState currentStatus play May 31 04:24:00 volumio-rp3 volumio[1199]: info: Received an update from plugin. extracting info from payload May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::pushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioPushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreStateMachine::pushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: CoreCommandRouter::volumioPushState May 31 04:24:00 volumio-rp3 volumio[1199]: info: ------------------------------ 29ms May 31 04:24:00 volumio-rp3 volumio[1199]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 31 04:24:00 volumio-rp3 volumio[1199]: info: FusionDsp - ---- read samplerate from file: 48000 May 31 04:24:00 volumio-rp3 volumio[1199]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 04:24:03 volumio-rp3 volumio[1199]: info: [youtube2] Refresh session PO token May 31 04:24:03 volumio-rp3 volumio[1199]: info: [youtube2] Obtained session PO token using datasyncIdToken (expires in 43103 seconds) May 31 04:24:03 volumio-rp3 volumio[1199]: info: [youtube2] Going to refresh session PO token in 43003 seconds May 31 04:24:03 volumio-rp3 volumio[1199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 04:24:03 volumio-rp3 volumio[1199]: TypeError: Chaining cycle detected for promise # May 31 04:24:03 volumio-rp3 volumio[1199]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:191:12) May 31 04:24:03 volumio-rp3 volumio[1199]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 04:24:03 volumio-rp3 volumio[1199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 04:24:04 volumio-rp3 sudo[28720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 04:23' May 31 04:24:04 volumio-rp3 sudo[28720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"