Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces system playlist update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Ignoring MPD Status Update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces state update: player Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::getState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand status Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces system playlist update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Ignoring MPD Status Update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces state update: player Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::getState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand status Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces system playlist update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Ignoring MPD Status Update Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Aug 29 03:18:46 volumio3040 volumio[2264101]: ---------------------------- MPD announces state update: player Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::getState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand status Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 4ms Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand status took 4 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 3ms Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand status took 3 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 2ms Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand status took 3 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseState Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseTrackInfo Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseTrackInfo Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::servicePushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CorePlayQueue::getTrack 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37153238,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eurythmics - There Must Be An Angel","artist":"Radio Veronica","album":null,"uri":"https://stream.radioveronica.nl?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NzY0NDQyNSwiaXNzIjoidGlzcnYifQ.pv9VncuUnYA1rES32kr8esgf1WHu_Lm9m3HdcsAl7xU","trackType":""} Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: CURRENT POSITION 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState stateService play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState currentStatus play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Received an update from plugin. extracting info from payload Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::servicePushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CorePlayQueue::getTrack 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37153238,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eurythmics - There Must Be An Angel","artist":"Radio Veronica","album":null,"uri":"https://stream.radioveronica.nl?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NzY0NDQyNSwiaXNzIjoidGlzcnYifQ.pv9VncuUnYA1rES32kr8esgf1WHu_Lm9m3HdcsAl7xU","trackType":""} Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: CURRENT POSITION 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState stateService play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState currentStatus play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Received an update from plugin. extracting info from payload Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 19ms Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 18ms Aug 29 03:18:46 volumio3040 volumio[2264101]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: ControllerMpd::parseTrackInfo Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ControllerMpd::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::servicePushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CorePlayQueue::getTrack 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37153238,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eurythmics - There Must Be An Angel","artist":"Radio Veronica","album":null,"uri":"https://stream.radioveronica.nl?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4NzY0NDQyNSwiaXNzIjoidGlzcnYifQ.pv9VncuUnYA1rES32kr8esgf1WHu_Lm9m3HdcsAl7xU","trackType":""} Aug 29 03:18:46 volumio3040 volumio[2264101]: verbose: CURRENT POSITION 0 Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState stateService play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::syncState currentStatus play Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Received an update from plugin. extracting info from payload Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreStateMachine::pushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: CoreCommandRouter::volumioPushState Aug 29 03:18:46 volumio3040 volumio[2264101]: info: ------------------------------ 31ms Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=xscreensaver Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- → Wakeup triggered Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:18:46 volumio3040 volumio[2264101]: info: Display-configuration --- wakeupScreen: xscreensaver deactivated (screen on) Aug 29 03:19:33 volumio3040 volumio[2264101]: info: [youtube2] Refresh session PO token Aug 29 03:19:33 volumio3040 volumio[2264101]: info: [youtube2] Obtained session PO token using visitorData (expires in 43101 seconds) Aug 29 03:19:33 volumio3040 volumio[2264101]: info: [youtube2] Going to refresh session PO token in 43001 seconds Aug 29 03:19:33 volumio3040 volumio[2264101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 03:19:33 volumio3040 volumio[2264101]: TypeError: Chaining cycle detected for promise # Aug 29 03:19:33 volumio3040 volumio[2264101]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:191:12) Aug 29 03:19:33 volumio3040 volumio[2264101]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 29 03:19:33 volumio3040 volumio[2264101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 03:19:33 volumio3040 sudo[2664576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 03:18' Aug 29 03:19:33 volumio3040 sudo[2664576]: 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"