Jan 13 07:58:32 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 13 07:58:37 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 13 07:58:37 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 13 07:58:57 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jan 13 07:58:57 volumio volumio[1087]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC-9-kyTW8ZkZNDHQJ6FgpwQ%22%7D%7D Jan 13 07:58:58 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jan 13 07:58:58 volumio volumio[1087]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEstorefront%22%2C%22params%22%3A%22ogUCKAU%253D%22%7D%7D Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:01 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:13 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:13 volumio volumio[1087]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 13 07:59:13 volumio volumio[1087]: info: CoreStateMachine::ClearQueue Jan 13 07:59:13 volumio volumio[1087]: info: CoreStateMachine::stop Jan 13 07:59:13 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 07:59:13 volumio volumio[1087]: info: CorePlayQueue::clearPlayQueue Jan 13 07:59:13 volumio volumio[1087]: info: CorePlayQueue::saveQueue Jan 13 07:59:13 volumio volumio[1087]: info: CoreCommandRouter::volumioPushQueue Jan 13 07:59:13 volumio volumio[1087]: info: CoreStateMachine::addQueueItems Jan 13 07:59:13 volumio volumio[1087]: info: CorePlayQueue::addQueueItems Jan 13 07:59:13 volumio volumio[1087]: info: Preload queue cleared Jan 13 07:59:14 volumio volumio[1087]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Wicked%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F79nraZGUzE0%2Fmovieposter_en.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2279nraZGUzE0%22%7D%7D%7D Jan 13 07:59:14 volumio volumio[1087]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Wicked%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F79nraZGUzE0%2Fmovieposter_en.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2279nraZGUzE0%22%7D%7D%7D in service youtube2 Jan 13 07:59:14 volumio volumio[1087]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Wicked%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F79nraZGUzE0%2Fmovieposter_en.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2279nraZGUzE0%22%7D%7D%7D Jan 13 07:59:14 volumio volumio[1087]: info: CoreCommandRouter::volumioPushQueue Jan 13 07:59:14 volumio volumio[1087]: info: CorePlayQueue::saveQueue Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::updateTrackBlock Jan 13 07:59:14 volumio volumio[1087]: info: CorePlayQueue::getTrackBlock Jan 13 07:59:14 volumio volumio[1087]: info: CoreCommandRouter::volumioPlay Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::play index 0 Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::stop Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::play index undefined Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 07:59:14 volumio volumio[1087]: info: CorePlayQueue::getTrack 0 Jan 13 07:59:14 volumio volumio[1087]: info: CoreStateMachine::startPlaybackTimer Jan 13 07:59:14 volumio volumio[1087]: info: CorePlayQueue::getTrack 0 Jan 13 07:59:14 volumio volumio[1087]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2279nraZGUzE0%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Wicked%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2F79nraZGUzE0%2Fmovieposter_en.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2279nraZGUzE0%22%7D%7D%7D Jan 13 07:59:14 volumio volumio[1087]: info: [ytmusic] Obtained PO token for video #79nraZGUzE0: MlUqPLYt6qk9qCoCNHdtA6H6-V5xz4-_qOIiAUpNy3QyGnS1iW1LUrHFJmFvFQNk4YThOFQ74YdTjTuRyan6R3QX-gSBa4W8Grap0pjBu0n90355dUYf Jan 13 07:59:16 volumio volumio[1087]: info: [ytmusic] Obtained PO token for video #79nraZGUzE0: MlXL5x_4PkzJN8vZnaK55lVlGIXYGltaXH3D2uOYH5HGhZVuILift0Vax7rGwNeBFRsA4_3uNWKnEtpKYHwuooCIG98ovlFZ7inIsOBatA3_iZ0ys4AX Jan 13 07:59:17 volumio volumio[1087]: info: [youtube2] VideoModel.getInfo(79nraZGUzE0): validating stream URL "https://rr1---sn-oguelnsy.googlevideo.com/videoplayback?expire=1768283957&ei=1YpladXxAeey2roPzKc5&ip=103.62.49.130&id=o-ABAWbx_Fo9UW-E_tyRSUv-23bEAyOB2ZJdUw95LIhbMR&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1768262357%2C&mh=-q&mm=31%2C29&mn=sn-oguelnsy%2Csn-oguesn6y&ms=au%2Crdu&mv=m&mvi=1&pl=25&rms=au%2Cau&initcwndbps=3123750&bui=AW-iu_oMx8ygpHpPcqLgcNiy-VeG1U2XmF4NMiT8-e9MV2dqOUhJiBLwzorllqYzXu_IjMVsdPPEKUkh&spc=q5xjPPYOqTHLRVkl5HRZo-ZSrvKQTtj9SI93MxzzwlpevIH9f0Ffwl5Xh20&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Den&mime=audio%2Fmp4&ns=8AyXogcAm1_he33zdepwfJMR&rqh=1&gir=yes&clen=1491975&dur=92.136&lmt=1746027770486611&mt=1768261981&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=n0vwbezlJxpyvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIgSBLJKrAIC6YJfwlgzAEQVRnZuxnv1MH9yNJR1vABTAECIQC-DDABDZAnkL2IugFrCV_iMfwxg2NDRMXrmf98ZSsgfQ%3D%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAIj7nxDKiuFiXcS1pugRnem4OBHxmHWgF75Q1Nff-0L8AiBSkp-W0UDY64YSGcLJKpQp8y9NSoHA8gZXewzQczVdsg%3D%3D&pot=MlXL5x_4PkzJN8vZnaK55lVlGIXYGltaXH3D2uOYH5HGhZVuILift0Vax7rGwNeBFRsA4_3uNWKnEtpKYHwuooCIG98ovlFZ7inIsOBatA3_iZ0ys4AX&cver=1.20250219.01.00"... Jan 13 07:59:20 volumio volumio[1087]: info: [youtube2] VideoModel.getInfo(79nraZGUzE0): stream validated in 3.003s. Jan 13 07:59:20 volumio volumio[1087]: info: [youtube2] (AutoplayManager) Added volumioStateListener Jan 13 07:59:20 volumio volumio[1087]: info: [youtube2] (AutoplayManager) Enabled Jan 13 07:59:20 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 07:59:20 volumio volumio[1087]: info: sendMpdCommand stop took 2 milliseconds Jan 13 07:59:20 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 07:59:20 volumio volumio[1087]: info: Jan 13 07:59:20 volumio volumio[1087]: ---------------------------- MPD announces system playlist update Jan 13 07:59:20 volumio volumio[1087]: info: Ignoring MPD Status Update Jan 13 07:59:20 volumio volumio[1087]: info: sendMpdCommand clear took 1 milliseconds Jan 13 07:59:20 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-oguelnsy.googlevideo.com/videoplayback?expire=1768283957&ei=1YpladXxAeey2roPzKc5&ip=103.62.49.130&id=o-ABAWbx_Fo9UW-E_tyRSUv-23bEAyOB2ZJdUw95LIhbMR&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1768262357%2C&mh=-q&mm=31%2C29&mn=sn-oguelnsy%2Csn-oguesn6y&ms=au%2Crdu&mv=m&mvi=1&pl=25&rms=au%2Cau&initcwndbps=3123750&bui=AW-iu_oMx8ygpHpPcqLgcNiy-VeG1U2XmF4NMiT8-e9MV2dqOUhJiBLwzorllqYzXu_IjMVsdPPEKUkh&spc=q5xjPPYOqTHLRVkl5HRZo-ZSrvKQTtj9SI93MxzzwlpevIH9f0Ffwl5Xh20&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Den&mime=audio%2Fmp4&ns=8AyXogcAm1_he33zdepwfJMR&rqh=1&gir=yes&clen=1491975&dur=92.136&lmt=1746027770486611&mt=1768261981&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=n0vwbezlJxpyvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIgSBLJKrAIC6YJfwlgzAEQVRnZuxnv1MH9yNJR1vABTAECIQC-DDABDZAnkL2IugFrCV_iMfwxg2NDRMXrmf98ZSsgfQ%3D%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAIj7nxDKiuFiXcS1pugRnem4OBHxmHWgF75Q1Nff-0L8AiBSkp-W0UDY64YSGcLJKpQp8y9NSoHA8gZXewzQczVdsg%3D%3D&pot=MlXL5x_4PkzJN8vZnaK55lVlGIXYGltaXH3D2uOYH5HGhZVuILift0Vax7rGwNeBFRsA4_3uNWKnEtpKYHwuooCIG98ovlFZ7inIsOBatA3_iZ0ys4AX&cver=1.20250219.01.00&t.YouTube" Jan 13 07:59:20 volumio volumio[1087]: error: updateQueue error: null Jan 13 07:59:20 volumio volumio[1087]: info: ------------------------------ 2ms Jan 13 07:59:20 volumio volumio[1087]: info: Jan 13 07:59:20 volumio volumio[1087]: ---------------------------- MPD announces system playlist update Jan 13 07:59:20 volumio volumio[1087]: info: Ignoring MPD Status Update Jan 13 07:59:20 volumio volumio[1087]: info: sendMpdCommand addid "https://rr1---sn-oguelnsy.googlevideo.com/videoplayback?expire=1768283957&ei=1YpladXxAeey2roPzKc5&ip=103.62.49.130&id=o-ABAWbx_Fo9UW-E_tyRSUv-23bEAyOB2ZJdUw95LIhbMR&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1768262357%2C&mh=-q&mm=31%2C29&mn=sn-oguelnsy%2Csn-oguesn6y&ms=au%2Crdu&mv=m&mvi=1&pl=25&rms=au%2Cau&initcwndbps=3123750&bui=AW-iu_oMx8ygpHpPcqLgcNiy-VeG1U2XmF4NMiT8-e9MV2dqOUhJiBLwzorllqYzXu_IjMVsdPPEKUkh&spc=q5xjPPYOqTHLRVkl5HRZo-ZSrvKQTtj9SI93MxzzwlpevIH9f0Ffwl5Xh20&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Den&mime=audio%2Fmp4&ns=8AyXogcAm1_he33zdepwfJMR&rqh=1&gir=yes&clen=1491975&dur=92.136&lmt=1746027770486611&mt=1768261981&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=n0vwbezlJxpyvg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIgSBLJKrAIC6YJfwlgzAEQVRnZuxnv1MH9yNJR1vABTAECIQC-DDABDZAnkL2IugFrCV_iMfwxg2NDRMXrmf98ZSsgfQ%3D%3D&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAIj7nxDKiuFiXcS1pugRnem4OBHxmHWgF75Q1Nff-0L8AiBSkp-W0UDY64YSGcLJKpQp8y9NSoHA8gZXewzQczVdsg%3D%3D&pot=MlXL5x_4PkzJN8vZnaK55lVlGIXYGltaXH3D2uOYH5HGhZVuILift0Vax7rGwNeBFRsA4_3uNWKnEtpKYHwuooCIG98ovlFZ7inIsOBatA3_iZ0ys4AX&cver=1.20250219.01.00&t.YouTube" took 13 milliseconds Jan 13 07:59:20 volumio volumio[1087]: verbose: MPD COMMAND [object Object] Jan 13 07:59:20 volumio volumio[1087]: verbose: MPD COMMAND [object Object] Jan 13 07:59:20 volumio volumio[1087]: verbose: MPD COMMAND [object Object] Jan 13 07:59:20 volumio volumio[1087]: info: Jan 13 07:59:20 volumio volumio[1087]: ---------------------------- MPD announces system playlist update Jan 13 07:59:20 volumio volumio[1087]: info: Ignoring MPD Status Update Jan 13 07:59:20 volumio volumio[1087]: info: ------------------------------ 4ms Jan 13 07:59:20 volumio volumio[1087]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 13 07:59:20 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand play Jan 13 07:59:20 volumio volumio[1087]: info: ------------------------------ 1ms Jan 13 07:59:20 volumio volumio[1087]: info: sendMpdCommand play took 67 milliseconds Jan 13 07:59:20 volumio volumio[1087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 07:59:20 volumio volumio[1087]: InnertubeError: Playback tracking not available Jan 13 07:59:20 volumio volumio[1087]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:30709:13) Jan 13 07:59:20 volumio volumio[1087]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:32099:18) Jan 13 07:59:20 volumio volumio[1087]: at Object.addToHistory (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:66:34) Jan 13 07:59:20 volumio volumio[1087]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:141:35) Jan 13 07:59:20 volumio volumio[1087]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Jan 13 07:59:20 volumio volumio[1087]: date: 2026-01-12T23:59:20.827Z, Jan 13 07:59:20 volumio volumio[1087]: version: '1.6.0', Jan 13 07:59:20 volumio volumio[1087]: info: undefined Jan 13 07:59:20 volumio volumio[1087]: } Jan 13 07:59:20 volumio volumio[1087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 07:59:20 volumio sudo[114057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 07:58' Jan 13 07:59:20 volumio sudo[114057]: 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"