Aug 27 11:30:09 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 6 Aug 27 11:30:09 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:09 volumio-pc volumio[1807]: info: Prefetching next song Aug 27 11:30:09 volumio-pc volumio[1807]: info: DOING PREFETCH IN MPD Aug 27 11:30:09 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Documentaries to sleep 11/James Webb Space Telescope Sunshield Deployment - Mission Control Live.mp3" Aug 27 11:30:09 volumio-pc volumio[1807]: info: Aug 27 11:30:09 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:09 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:09 volumio-pc volumio[1807]: info: sendMpdCommand add "INTERNAL/Documentaries to sleep 11/James Webb Space Telescope Sunshield Deployment - Mission Control Live.mp3" took 1 milliseconds Aug 27 11:30:09 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 27 11:30:09 volumio-pc volumio[1807]: info: Aug 27 11:30:09 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:09 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:09 volumio-pc volumio[1807]: info: Aug 27 11:30:09 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:09 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:09 volumio-pc volumio[1807]: info: ------------------------------ 1ms Aug 27 11:30:09 volumio-pc volumio[1807]: info: sendMpdCommand consume 1 took 1 milliseconds Aug 27 11:30:09 volumio-pc volumio[1807]: info: ------------------------------ 1ms Aug 27 11:30:09 volumio-pc volumio[1807]: info: ------------------------------ 1ms Aug 27 11:30:13 volumio-pc volumio[1807]: info: CoreStateMachine::startPlaybackTimer Aug 27 11:30:13 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces state update: player Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::getState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand status Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces state update: player Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::getState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand status Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces system playlist update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Ignoring MPD Status Update Aug 27 11:30:14 volumio-pc volumio[1807]: info: Aug 27 11:30:14 volumio-pc volumio[1807]: ---------------------------- MPD announces state update: player Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::getState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand status Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 0ms Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand status took 0 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 1ms Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand status took 1 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 1ms Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand status took 1 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseState Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseTrackInfo Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::servicePushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":9159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"James Webb Space Telescope: Sunshield Deployment - Mission Control Live","artist":null,"album":null,"uri":"INTERNAL/Documentaries to sleep 11/James Webb Space Telescope Sunshield Deployment - Mission Control Live.mp3","trackType":"mp3"} Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: CURRENT POSITION 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState stateService play Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState currentStatus play Aug 27 11:30:14 volumio-pc volumio[1807]: info: Received an update from plugin. extracting info from payload Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 3ms Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseTrackInfo Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: ControllerMpd::parseTrackInfo Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::servicePushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":9159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"James Webb Space Telescope: Sunshield Deployment - Mission Control Live","artist":null,"album":null,"uri":"INTERNAL/Documentaries to sleep 11/James Webb Space Telescope Sunshield Deployment - Mission Control Live.mp3","trackType":"mp3"} Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: CURRENT POSITION 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState stateService play Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState currentStatus play Aug 27 11:30:14 volumio-pc volumio[1807]: info: Received an update from plugin. extracting info from payload Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: ControllerMpd::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::servicePushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":9159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"James Webb Space Telescope: Sunshield Deployment - Mission Control Live","artist":null,"album":null,"uri":"INTERNAL/Documentaries to sleep 11/James Webb Space Telescope Sunshield Deployment - Mission Control Live.mp3","trackType":"mp3"} Aug 27 11:30:14 volumio-pc volumio[1807]: verbose: CURRENT POSITION 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState stateService play Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::syncState currentStatus play Aug 27 11:30:14 volumio-pc volumio[1807]: info: Received an update from plugin. extracting info from payload Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreStateMachine::pushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: CorePlayQueue::getTrack 7 Aug 27 11:30:14 volumio-pc volumio[1807]: info: CoreCommandRouter::volumioPushState Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 5ms Aug 27 11:30:14 volumio-pc volumio[1807]: info: ------------------------------ 5ms Aug 27 11:30:42 volumio-pc volumio[1807]: info: [ytcr] Refresh session PO token Aug 27 11:30:42 volumio-pc volumio[1807]: info: [ytcr] Obtained session PO token using visitorData (expires in 43101 seconds) Aug 27 11:30:42 volumio-pc volumio[1807]: info: [ytcr] Going to refresh session PO token in 43001 seconds Aug 27 11:30:42 volumio-pc volumio[1807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:30:42 volumio-pc volumio[1807]: TypeError: Chaining cycle detected for promise # Aug 27 11:30:42 volumio-pc volumio[1807]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/ytcr/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:187:12) Aug 27 11:30:42 volumio-pc volumio[1807]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 27 11:30:42 volumio-pc volumio[1807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:30:42 volumio-pc sudo[1373273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 11:29' Aug 27 11:30:42 volumio-pc sudo[1373273]: 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/"