-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-29 12:37:22 CEST. -- May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76a96807 does not match aorg 0000000000.00000000 from server@195.201.20.16 xmt 0xebe2b761.5dbb19be May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76adff33 does not match aorg 0000000000.00000000 from server@188.245.32.133 xmt 0xebe2b761.5dd2be00 May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76ac4229 does not match aorg 0000000000.00000000 from server@109.123.244.54 xmt 0xebe2b761.5da998a4 May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76a6e460 does not match aorg 0000000000.00000000 from server@49.13.14.46 xmt 0xebe2b761.5ddeec8f May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76aa560b does not match aorg 0000000000.00000000 from server@128.127.67.142 xmt 0xebe2b761.5e5060cc May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76a85bcf does not match aorg 0000000000.00000000 from server@45.136.29.138 xmt 0xebe2b761.5f9bad06 May 29 12:37:21 volumio-player ntpd[1435]: receive: Unexpected origin timestamp 0xebe16365.76a1dbac does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xebe2b761.609fa160 May 29 12:37:21 volumio-player systemd[1]: Starting Daily apt download activities... May 29 12:37:21 volumio-player systemd[1]: Starting Daily man-db regeneration... May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 12:37:21 volumio-player volumio[907]: info: Discovery: A device disappeared from network May 29 12:37:21 volumio-player volumio[907]: info: Discovery: A device disappeared from network May 29 12:37:21 volumio-player volumio[907]: info: Discovery: Device volumio-player disappeared from network May 29 12:37:21 volumio-player volumio[907]: info: Discovery: A device disappeared from network May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 12:37:21 volumio-player volumio[907]: info: Received Get System Info May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 12:37:21 volumio-player volumio[907]: info: Discovery: Getting this device information May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:21 volumio-player volumio[907]: info: Listing playlists May 29 12:37:21 volumio-player sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=peter,password=ruthpwd,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.4.201/music/HiRes/ /mnt/NAS/Heimserver1 May 29 12:37:21 volumio-player sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 12:37:21 volumio-player volumio[907]: info: Discovery: adding 6f7c7d12-6843-4474-ac66-1133693b6f11 May 29 12:37:21 volumio-player volumio[907]: info: Discovery: Found device Volumio-Player May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:21 volumio-player kernel: CIFS: Attempting to mount //192.168.4.201/music/HiRes May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 12:37:21 volumio-player volumio[907]: info: MyVolumio token set successfully May 29 12:37:21 volumio-player volumio[907]: info: MYVOLUMIO: Adding device May 29 12:37:21 volumio-player volumio[907]: info: MYVOLUMIO: Evaluating Server May 29 12:37:21 volumio-player volumio[907]: info: Discovery: this is already registered, 6f7c7d12-6843-4474-ac66-1133693b6f11 May 29 12:37:21 volumio-player volumio[907]: info: Discovery: Found device Volumio-Player May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:21 volumio-player volumio[907]: info: Discovery: this is already registered, 6f7c7d12-6843-4474-ac66-1133693b6f11 May 29 12:37:21 volumio-player volumio[907]: info: Discovery: Found device Volumio-Player May 29 12:37:21 volumio-player volumio[907]: info: CoreCommandRouter::volumioGetState May 29 12:37:21 volumio-player volumio[907]: info: CorePlayQueue::getTrack 0 May 29 12:37:22 volumio-player sudo[1628]: pam_unix(sudo:session): session closed for user root May 29 12:37:22 volumio-player volumio-time-update[603]: volumio-time-update-util: Setting system time to: 2025-05-29 12:37:22 May 29 12:37:22 volumio-player sudo[1670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-29 12:37:22 May 29 12:37:22 volumio-player sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 12:37:22 volumio-player dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=1671 comm="timedatectl set-time 2025-05-29 12:37:22 ") May 29 12:37:22 volumio-player volumio[907]: info: MyVolumio status changed May 29 12:37:22 volumio-player volumio[907]: info: Streaming services startup May 29 12:37:22 volumio-player volumio[907]: info: Starting Streaming Daemon May 29 12:37:22 volumio-player systemd[1]: Starting Time & Date Service... May 29 12:37:22 volumio-player volumio[907]: info: Removing browser output: myVolumio user plan is not superstar May 29 12:37:22 volumio-player volumio[907]: info: Removing audio output: May 29 12:37:22 volumio-player volumio[907]: info: Stoppping Tunnel 1 May 29 12:37:22 volumio-player sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 12:37:22 volumio-player sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 12:37:22 volumio-player sudo[1679]: pam_unix(sudo:session): session closed for user root May 29 12:37:22 volumio-player volumio[907]: error: Cannot start Volumio Streaming Daemon May 29 12:37:22 volumio-player dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 12:37:22 volumio-player systemd[1]: Started Time & Date Service. May 29 12:37:22 volumio-player volumio[907]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 12:37:22 volumio-player volumio[907]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 12:37:22 volumio-player sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 12:37:22 volumio-player sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 12:37:22 volumio-player systemd-timedated[1676]: Changed local time to Thu May 29 12:37:22 2025 May 29 12:37:22 volumio-player sudo[1670]: pam_unix(sudo:session): session closed for user root May 29 12:37:22 volumio-player volumio-time-update[603]: volumio-time-update-util: System time updated successfully. May 29 12:37:22 volumio-player systemd[1]: Started Volumio Time Update Utility. May 29 12:37:22 volumio-player systemd[1]: Reached target Multi-User System. May 29 12:37:22 volumio-player systemd[1]: Reached target Graphical Interface. May 29 12:37:22 volumio-player volumio[907]: error: Failed to ping endpoint as1.myvolumio.org : unknown error May 29 12:37:22 volumio-player volumio[907]: error: Failed to ping endpoint us3.myvolumio.org : unknown error May 29 12:37:22 volumio-player volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 12:37:22 volumio-player volumio[907]: Error: Unable to resolve or reject the same promise twice May 29 12:37:22 volumio-player volumio[907]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 29 12:37:22 volumio-player volumio[907]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 29 12:37:22 volumio-player volumio[907]: at Socket.emit (events.js:327:22) May 29 12:37:22 volumio-player volumio[907]: at endReadableNT (internal/streams/readable.js:1327:12) May 29 12:37:22 volumio-player volumio[907]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 29 12:37:22 volumio-player volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 12:37:22 volumio-player systemd[1]: Starting Update UTMP about System Runlevel Changes... May 29 12:37:22 volumio-player sudo[1682]: pam_unix(sudo:session): session closed for user root May 29 12:37:22 volumio-player systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 29 12:37:22 volumio-player systemd[1]: Started Update UTMP about System Runlevel Changes. May 29 12:37:22 volumio-player systemd[1]: systemd-hostnamed.service: Succeeded. May 29 12:37:22 volumio-player sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 12:36 May 29 12:37:22 volumio-player sudo[1698]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"