Mar 29 20:17:00 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 20:17:00 volumiorpi2 volumio[1114]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 29 20:17:00 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 20:17:00 volumiorpi2 volumio5-onboarding[1779]: time=2026-03-29T20:17:00.539+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 29 20:17:00 volumiorpi2 volumio5-onboarding[1779]: time=2026-03-29T20:17:00.541+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 29 20:17:00 volumiorpi2 volumio5-onboarding[1779]: time=2026-03-29T20:17:00.542+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 29 20:17:00 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 20:17:01 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 20:17:01 volumiorpi2 volumio[1114]: info: CoreCommandRouter::volumioGetState Mar 29 20:17:01 volumiorpi2 volumio[1114]: info: CorePlayQueue::getTrack 0 Mar 29 20:17:01 volumiorpi2 volumio[1114]: info: MyVolumio login type: Token Mar 29 20:17:04 volumiorpi2 volumio[1114]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 20:17:05 volumiorpi2 volumio[1114]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 29 20:17:06 volumiorpi2 volumio[1114]: info: MyVolumio token set successfully Mar 29 20:17:06 volumiorpi2 volumio[1114]: info: MYVOLUMIO: Adding device Mar 29 20:17:06 volumiorpi2 volumio[1114]: info: MYVOLUMIO: Evaluating Server Mar 29 20:17:08 volumiorpi2 volumio[1114]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: Completed starting MyVolumio Plugin Mar 29 20:17:08 volumiorpi2 volumio[1114]: [Metrics] CommandRouter: 128s 743.93ms Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: VolumeController:: Setting startup Volume 50 Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: VolumeController::SetAlsaVolume50 Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreStateMachine::pushState Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CorePlayQueue::getTrack 0 Mar 29 20:17:08 volumiorpi2 volumio[1114]: info: CoreCommandRouter::volumioPushState Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: MyVolumio status changed Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: Streaming services startup Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: Starting Streaming Daemon Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: Removing audio output: Mar 29 20:17:09 volumiorpi2 volumio[1114]: info: Stoppping Tunnel 1 Mar 29 20:17:09 volumiorpi2 sudo[1850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 20:17:09 volumiorpi2 sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:17:09 volumiorpi2 sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 29 20:17:09 volumiorpi2 sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:17:10 volumiorpi2 sudo[1850]: pam_unix(sudo:session): session closed for user root Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 20:17:10 volumiorpi2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 20:17:10 volumiorpi2 sudo[1852]: pam_unix(sudo:session): session closed for user root Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: Setting Geolocation for MyVolumio to eu4 Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: Remote SSH Stopped Mar 29 20:17:10 volumiorpi2 volumio[1114]: error: Cannot start Volumio Streaming Daemon Mar 29 20:17:10 volumiorpi2 volumio[1114]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 20:17:10 volumiorpi2 volumio[1114]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 20:17:10 volumiorpi2 volumio[1114]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 29 20:17:10 volumiorpi2 volumio[1114]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 20:17:11 volumiorpi2 volumio[1114]: info: Updating MyVolumio device info Mar 29 20:17:11 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:11 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:11 volumiorpi2 volumio[1114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 20:17:12 volumiorpi2 volumio[1114]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 20:17:14 volumiorpi2 volumio[1114]: info: MYVOLUMIO: Adding device Mar 29 20:17:14 volumiorpi2 volumio[1114]: info: MYVOLUMIO: Evaluating Server Mar 29 20:17:16 volumiorpi2 volumio[1114]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 29 20:17:16 volumiorpi2 volumio[1114]: info: BOOT COMPLETED Mar 29 20:17:21 volumiorpi2 volumio[1114]: info: AutoStart - Check #11/60 - VOLUMIO_SYSTEM_STATUS = ready Mar 29 20:17:21 volumiorpi2 volumio[1114]: info: AutoStart - System ready state CONFIRMED after 11 checks Mar 29 20:17:21 volumiorpi2 volumio[1114]: info: AutoStart - Applying additional delay of 5000ms before playback Mar 29 20:17:26 volumiorpi2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: AutoStart - startPlayback called Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: CoreCommandRouter::volumioGetQueue Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: CoreStateMachine::getQueue Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: CorePlayQueue::getQueue Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: AutoStart - Queue has 1 items Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: AutoStart - Playing from position 0 Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: CoreCommandRouter::volumioPlay Mar 29 20:17:26 volumiorpi2 volumio[1114]: info: CoreStateMachine::play index 0 Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::stop Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::play index undefined Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CorePlayQueue::getTrack 0 Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CoreStateMachine::startPlaybackTimer Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: CorePlayQueue::getTrack 0 Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: [1774808247153] ControllerWebradio::clearAddPlayTrack Mar 29 20:17:27 volumiorpi2 volumio[1114]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: sendMpdCommand stop took 5 milliseconds Mar 29 20:17:27 volumiorpi2 volumio[1114]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Mar 29 20:17:27 volumiorpi2 volumio[1114]: ---------------------------- MPD announces system playlist update Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Ignoring MPD Status Update Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: sendMpdCommand clear took 61 milliseconds Mar 29 20:17:27 volumiorpi2 volumio[1114]: verbose: ControllerMpd::sendMpdCommand load "https://radiorecord.hostingradio.ru/197096.aacp" Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Mar 29 20:17:27 volumiorpi2 volumio[1114]: ---------------------------- MPD announces system playlist update Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Ignoring MPD Status Update Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Mar 29 20:17:27 volumiorpi2 volumio[1114]: ---------------------------- MPD announces system playlist update Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: Ignoring MPD Status Update Mar 29 20:17:27 volumiorpi2 volumio[1114]: error: updateQueue error: null Mar 29 20:17:27 volumiorpi2 volumio[1114]: info: ------------------------------ 157ms Mar 29 20:17:40 volumiorpi2 systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Mar 29 20:17:40 volumiorpi2 systemd[1]: mpd.service: Killing process 1527 (mpd) with signal SIGABRT. Mar 29 20:17:40 volumiorpi2 systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Mar 29 20:17:40 volumiorpi2 systemd[1]: mpd.service: Failed with result 'watchdog'. Mar 29 20:17:40 volumiorpi2 systemd[1]: mpd.service: Consumed 12.672s CPU time. Mar 29 20:17:40 volumiorpi2 volumio[1114]: error: MPD error: Error: read ECONNRESET Mar 29 20:17:40 volumiorpi2 volumio[1114]: error: MPD error: Error: read ECONNRESET Mar 29 20:17:40 volumiorpi2 volumio[1114]: error: MPD error: Error: read ECONNRESET Mar 29 20:17:43 volumiorpi2 volumio[1114]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Mar 29 20:17:43 volumiorpi2 volumio[1114]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Mar 29 20:17:43 volumiorpi2 volumio[1114]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Mar 29 20:17:43 volumiorpi2 volumio[1114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 20:17:43 volumiorpi2 volumio[1114]: Error: Unable to resolve or reject the same promise twice Mar 29 20:17:43 volumiorpi2 volumio[1114]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at Socket.emit (node:events:514:28) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at addChunk (node:internal/streams/readable:343:12) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at Readable.push (node:internal/streams/readable:253:10) Mar 29 20:17:43 volumiorpi2 volumio[1114]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 29 20:17:43 volumiorpi2 volumio[1114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 20:17:53 volumiorpi2 volumio5-onboarding[1779]: time=2026-03-29T20:17:53.950+02:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Mar 29 20:18:00 volumiorpi2 sudo[1964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 20:17' Mar 29 20:18:00 volumiorpi2 sudo[1964]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"