-- Logs begin at Wed 2025-12-03 20:03:05 CET, end at Tue 2025-12-16 13:15:27 CET. -- Dec 16 13:15:25 dar-1000es ntpd[622]: receive: Unexpected origin timestamp 0xecdb0848.5498d152 does not match aorg 0000000000.00000000 from server@194.182.84.241 xmt 0xecebcbdd.90f82072 Dec 16 13:15:25 dar-1000es ntpd[622]: receive: Unexpected origin timestamp 0xecdb0848.549d16cd does not match aorg 0000000000.00000000 from server@188.124.59.142 xmt 0xecebcbdd.917a2489 Dec 16 13:15:25 dar-1000es systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 13:15:25 dar-1000es systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 16 13:15:25 dar-1000es ntpd[622]: receive: Unexpected origin timestamp 0xecdb0848.549b0d9e does not match aorg 0000000000.00000000 from server@94.124.107.190 xmt 0xecebcbdd.9243c003 Dec 16 13:15:25 dar-1000es systemd[1]: Starting Daily man-db regeneration... Dec 16 13:15:25 dar-1000es volumio[828]: info: [1765887325590] Starting BluetoothController Dec 16 13:15:25 dar-1000es systemd[1]: Starting Daily apt download activities... Dec 16 13:15:25 dar-1000es systemd[1]: Stopped go-librespot Daemon. Dec 16 13:15:25 dar-1000es systemd[1]: Started go-librespot Daemon. Dec 16 13:15:25 dar-1000es go-librespot[1759]: Librespot-go daemon starting... Dec 16 13:15:25 dar-1000es go-librespot[1759]: time="2025-12-16T13:15:25+01:00" level=info msg="generated new device id: 6cea733358120fcbb8748811a5fc3dc5f18471e2" Dec 16 13:15:25 dar-1000es volumio-streaming-daemon[1711]: Enabled services: Dec 16 13:15:25 dar-1000es volumio-streaming-daemon[1711]: 1) tidal Dec 16 13:15:25 dar-1000es volumio-streaming-daemon[1711]: 2) qobuz Dec 16 13:15:25 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 16 13:15:25 dar-1000es go-librespot[1759]: time="2025-12-16T13:15:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 16 13:15:25 dar-1000es go-librespot[1759]: time="2025-12-16T13:15:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 16 13:15:25 dar-1000es go-librespot[1759]: time="2025-12-16T13:15:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 16 13:15:25 dar-1000es go-librespot[1759]: time="2025-12-16T13:15:25+01:00" level=debug msg="zeroconf server listening on port 45075" Dec 16 13:15:25 dar-1000es systemd[1]: man-db.service: Succeeded. Dec 16 13:15:25 dar-1000es systemd[1]: Started Daily man-db regeneration. Dec 16 13:15:26 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 16 13:15:26 dar-1000es sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Dec 16 13:15:26 dar-1000es sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 13:15:26 dar-1000es sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 16 13:15:26 dar-1000es sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 13:15:26 dar-1000es sudo[1800]: pam_unix(sudo:session): session closed for user root Dec 16 13:15:26 dar-1000es sudo[1802]: pam_unix(sudo:session): session closed for user root Dec 16 13:15:27 dar-1000es volumio[828]: info: MultiRoom plugin initialized Dec 16 13:15:27 dar-1000es volumio[828]: STOPPING SNAPCLIENT Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 16 13:15:27 dar-1000es sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 16 13:15:27 dar-1000es sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 13:15:27 dar-1000es sudo[1819]: pam_unix(sudo:session): session closed for user root Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 16 13:15:27 dar-1000es volumio[828]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 16 13:15:27 dar-1000es volumio[828]: info: Preparing to generate the ALSA configuration file Dec 16 13:15:27 dar-1000es volumio[828]: info: Initializing connection to go-librespot Websocket Dec 16 13:15:27 dar-1000es volumio[828]: info: Updating MyVolumio device info Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 13:15:27 dar-1000es volumio[828]: info: Reading ALSA contributions from plugins. Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::volumioGetState Dec 16 13:15:27 dar-1000es volumio[828]: info: CorePlayQueue::getTrack 0 Dec 16 13:15:27 dar-1000es volumio[828]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Dec 16 13:15:27 dar-1000es volumio[828]: info: Setting Geolocation for MyVolumio to eu6 Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 13:15:27 dar-1000es volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 13:15:27 dar-1000es volumio[828]: Error: Unable to resolve or reject the same promise twice Dec 16 13:15:27 dar-1000es volumio[828]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 16 13:15:27 dar-1000es volumio[828]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:31086) Dec 16 13:15:27 dar-1000es volumio[828]: at Socket.emit (events.js:327:22) Dec 16 13:15:27 dar-1000es volumio[828]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 16 13:15:27 dar-1000es volumio[828]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 16 13:15:27 dar-1000es volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 13:15:27 dar-1000es sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 13:14 Dec 16 13:15:27 dar-1000es sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"