-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-01 13:18:16 CEST. -- May 01 13:18:08 volumio ntpd[1261]: receive: Unexpected origin timestamp 0xebb0d41b.64f2cbf0 does not match aorg 0000000000.00000000 from server@194.25.115.122 xmt 0xebbdd6f0.93a533df May 01 13:18:08 volumio systemd[1]: Starting Daily man-db regeneration... May 01 13:18:08 volumio systemd[1]: Starting Daily apt download activities... May 01 13:18:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 13:18:08 volumio systemd[1]: man-db.service: Succeeded. May 01 13:18:08 volumio systemd[1]: Started Daily man-db regeneration. May 01 13:18:08 volumio volumio[822]: info: MyVolumio login type: Token May 01 13:18:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::volumioGetVisibleSources May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:09 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 13:18:09 volumio volumio[822]: info: Received Get System Info May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 13:18:09 volumio volumio[822]: info: Discovery: Getting this device information May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:09 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:09 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:09 volumio volumio[822]: info: Listing playlists May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 13:18:09 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 13:18:09 volumio volumio[822]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:10 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:10 volumio volumio[822]: info: MyVolumio token set successfully May 01 13:18:10 volumio volumio[822]: info: MYVOLUMIO: Adding device May 01 13:18:10 volumio volumio[822]: info: MYVOLUMIO: Evaluating Server May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 13:18:10 volumio volumio[822]: info: Received Get System Info May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 13:18:10 volumio volumio[822]: info: Discovery: Getting this device information May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:10 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:10 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 13:18:11 volumio volumio[822]: info: MyVolumio status changed May 01 13:18:11 volumio volumio[822]: info: Streaming services startup May 01 13:18:11 volumio volumio[822]: info: Starting Streaming Daemon May 01 13:18:11 volumio systemd[1]: apt-daily.service: Succeeded. May 01 13:18:11 volumio systemd[1]: Started Daily apt download activities. May 01 13:18:11 volumio volumio[822]: info: Removing browser output: myVolumio user plan is not superstar May 01 13:18:11 volumio volumio[822]: info: Removing audio output: May 01 13:18:11 volumio volumio[822]: info: Stoppping Tunnel 1 May 01 13:18:11 volumio sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 13:18:11 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:18:11 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 01 13:18:11 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 13:18:11 volumio sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 01 13:18:11 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:18:11 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root May 01 13:18:11 volumio volumio[822]: error: Cannot start Volumio Streaming Daemon May 01 13:18:11 volumio volumio[822]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 01 13:18:11 volumio volumio[822]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 01 13:18:11 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root May 01 13:18:11 volumio volumio[822]: info: Remote SSH Stopped May 01 13:18:11 volumio volumio[822]: info: Setting Geolocation for MyVolumio to eu5 May 01 13:18:11 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:18:11 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:18:11 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:18:11 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 01 13:18:12 volumio volumio[822]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 01 13:18:12 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 01 13:18:12 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 01 13:18:12 volumio volumio[822]: info: Updating MyVolumio device info May 01 13:18:12 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:18:13 volumio volumio[822]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 01 13:18:13 volumio volumio[822]: info: Discovery: A device disappeared from network May 01 13:18:13 volumio volumio[822]: info: Discovery: Device volumio disappeared from network May 01 13:18:13 volumio volumio[822]: info: Discovery: adding df19bb8c-255e-4dee-b18e-9865ba62d58e May 01 13:18:13 volumio volumio[822]: info: Discovery: Found device Volumio May 01 13:18:13 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:13 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:13 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 01 13:18:14 volumio volumio[822]: info: AutoStart - Plugin is starting May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::getQueue May 01 13:18:14 volumio volumio[822]: info: CorePlayQueue::getQueue May 01 13:18:14 volumio volumio[822]: info: AutoStart - start playing May 01 13:18:14 volumio volumio[822]: info: AutoStart - start playing with no specific position May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::volumioPlay May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::play index 0 May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::stop May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::play index undefined May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 13:18:14 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:14 volumio volumio[822]: info: CoreStateMachine::startPlaybackTimer May 01 13:18:14 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:14 volumio volumio[822]: info: [1746098294497] ControllerWebradio::clearAddPlayTrack May 01 13:18:14 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 01 13:18:14 volumio volumio[822]: info: sendMpdCommand stop took 2 milliseconds May 01 13:18:14 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand clear May 01 13:18:14 volumio volumio[822]: info: May 01 13:18:14 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 01 13:18:14 volumio volumio[822]: info: Ignoring MPD Status Update May 01 13:18:14 volumio volumio[822]: info: sendMpdCommand clear took 2 milliseconds May 01 13:18:14 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand load "https://streams.radiobob.de/70errock/mp3-192/tunein/" May 01 13:18:14 volumio volumio[822]: info: May 01 13:18:14 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 01 13:18:14 volumio volumio[822]: info: Ignoring MPD Status Update May 01 13:18:14 volumio volumio[822]: error: updateQueue error: null May 01 13:18:14 volumio volumio[822]: info: ------------------------------ 4ms May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 13:18:14 volumio volumio[822]: info: Received Get System Info May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 13:18:14 volumio volumio[822]: info: Discovery: Getting this device information May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 01 13:18:14 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 01 13:18:14 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 13:18:16 volumio volumio[822]: info: MYVOLUMIO: Adding device May 01 13:18:16 volumio volumio[822]: info: MYVOLUMIO: Evaluating Server May 01 13:18:16 volumio dbus-daemon[560]: [system] Successfully activated service 'org.freedesktop.hostname1' May 01 13:18:16 volumio systemd[1]: Started Hostname Service. May 01 13:18:16 volumio dbus-daemon[560]: [system] Successfully activated service 'org.freedesktop.timedate1' May 01 13:18:16 volumio systemd[1]: Started Time & Date Service. May 01 13:18:00 volumio systemd-timedated[1369]: Changed local time to Thu May 1 13:18:00 2025 May 01 13:18:00 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root May 01 13:18:00 volumio volumio-time-update[565]: volumio-time-update-util: System time updated successfully. May 01 13:18:00 volumio systemd[1]: Started Volumio Time Update Utility. May 01 13:18:00 volumio systemd[1]: Reached target Multi-User System. May 01 13:18:00 volumio systemd[1]: Reached target Graphical Interface. May 01 13:18:00 volumio volumio[822]: error: updateQueue error: null May 01 13:18:00 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand add "https://streams.radiobob.de/70errock/mp3-192/tunein/" May 01 13:18:00 volumio volumio[822]: info: ------------------------------ -14418ms May 01 13:18:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 01 13:18:00 volumio volumio[822]: error: Failed to ping endpoint eu9.myvolumio.org : unknown error May 01 13:18:00 volumio volumio[822]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 13:18:00 volumio volumio[822]: Error: Unable to resolve or reject the same promise twice May 01 13:18:00 volumio volumio[822]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 01 13:18:00 volumio volumio[822]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) May 01 13:18:00 volumio volumio[822]: at Socket.emit (events.js:315:20) May 01 13:18:00 volumio volumio[822]: at addChunk (internal/streams/readable.js:309:12) May 01 13:18:00 volumio volumio[822]: at readableAddChunk (internal/streams/readable.js:284:9) May 01 13:18:00 volumio volumio[822]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 01 13:18:00 volumio volumio[822]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 01 13:18:00 volumio volumio[822]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 13:18:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 01 13:18:00 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 01 13:18:00 volumio systemd[1]: Startup finished in 12.347s (kernel) + 35.197s (userspace) = 47.545s. May 01 13:18:00 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 13:17 May 01 13:18:00 volumio sudo[1893]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"