-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-06-26 09:21:59 CEST. -- Jun 26 09:20:00 volumioprace volumio[6360]: info: MyVolumio status changed Jun 26 09:20:00 volumioprace volumio[6360]: info: Streaming services startup Jun 26 09:20:00 volumioprace volumio[6360]: info: Starting Streaming Daemon Jun 26 09:20:00 volumioprace volumio[6360]: info: Removing browser output: myVolumio user plan is not superstar Jun 26 09:20:00 volumioprace volumio[6360]: info: Removing audio output: Jun 26 09:20:00 volumioprace volumio[6360]: info: Stoppping Tunnel 1 Jun 26 09:20:00 volumioprace sudo[6727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 26 09:20:00 volumioprace sudo[6727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 09:20:00 volumioprace sudo[6730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 26 09:20:00 volumioprace sudo[6730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 09:20:00 volumioprace sudo[6727]: pam_unix(sudo:session): session closed for user root Jun 26 09:20:00 volumioprace volumio[6360]: error: Cannot start Volumio Streaming Daemon Jun 26 09:20:00 volumioprace volumio[6360]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 26 09:20:00 volumioprace volumio[6360]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 26 09:20:00 volumioprace sudo[6730]: pam_unix(sudo:session): session closed for user root Jun 26 09:20:00 volumioprace volumio[6360]: info: Remote SSH Stopped Jun 26 09:20:00 volumioprace volumio[6360]: info: Setting Geolocation for MyVolumio to eu4 Jun 26 09:20:00 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:01 volumioprace volumio[6360]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 26 09:20:01 volumioprace volumio[6360]: info: Updating MyVolumio device info Jun 26 09:20:01 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:01 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:01 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:01 volumioprace volumio[6360]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 26 09:20:02 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 26 09:20:05 volumioprace volumio[6360]: info: MYVOLUMIO: Adding device Jun 26 09:20:05 volumioprace volumio[6360]: info: MYVOLUMIO: Evaluating Server Jun 26 09:20:05 volumioprace volumio[6360]: info: Setting Geolocation for MyVolumio to eu4 Jun 26 09:20:05 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:05 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:05 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:06 volumioprace volumio[6360]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 26 09:20:06 volumioprace volumio[6360]: info: Updating MyVolumio device info Jun 26 09:20:06 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:07 volumioprace volumio[6360]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 26 09:20:10 volumioprace volumio[6360]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 26 09:20:10 volumioprace volumio[6360]: info: Received Get System Version Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 09:20:10 volumioprace volumio[6360]: info: Received Get System Info Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 09:20:10 volumioprace volumio[6360]: info: Discovery: Getting this device information Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::volumioGetState Jun 26 09:20:10 volumioprace volumio[6360]: info: CorePlayQueue::getTrack 0 Jun 26 09:20:10 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 09:20:13 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 26 09:20:13 volumioprace volumio[6360]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 26 09:20:13 volumioprace volumio[6360]: info: CoreCommandRouter::volumioGetState Jun 26 09:20:13 volumioprace volumio[6360]: info: CorePlayQueue::getTrack 0 Jun 26 09:20:17 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 09:20:17 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:17 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 26 09:20:17 volumioprace volumio-remote-updater[594]: Test mode enabled Jun 26 09:20:17 volumioprace volumio-remote-updater[594]: No alpha test mode Jun 26 09:20:17 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 26 09:20:17 volumioprace volumio[6360]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 26 09:20:17 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::volumioGetBrowseSources Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 09:20:18 volumioprace volumio[6360]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 26 09:20:18 volumioprace volumio[6360]: info: Completed starting MyVolumio Plugin Jun 26 09:20:18 volumioprace volumio[6360]: [Metrics] CommandRouter: 35s 358.76ms Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::volumiosetStartupVolume Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::Close All Modals sent Jun 26 09:20:18 volumioprace volumio[6360]: info: CoreCommandRouter::Close All Modals sent Jun 26 09:20:19 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 26 09:20:19 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 09:20:19 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 26 09:20:21 volumioprace nmbd[703]: [2025/06/26 09:20:21.227703, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 26 09:20:21 volumioprace nmbd[703]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.153 for name WORKGROUP<1d>. Jun 26 09:20:21 volumioprace nmbd[703]: This response was from IP 192.168.10.226, reporting an IP address of 192.168.10.226. Jun 26 09:20:21 volumioprace nmbd[703]: [2025/06/26 09:20:21.227975, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 26 09:20:21 volumioprace nmbd[703]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.154 for name WORKGROUP<1d>. Jun 26 09:20:21 volumioprace nmbd[703]: This response was from IP 192.168.10.226, reporting an IP address of 192.168.10.226. Jun 26 09:20:25 volumioprace volumio[6360]: info: BOOT COMPLETED Jun 26 09:21:32 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 26 09:21:32 volumioprace volumio[6360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 26 09:21:56 volumioprace volumio[6360]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/buster/armhf Jun 26 09:21:58 volumioprace volumio[6360]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/buster/armhf Jun 26 09:21:58 volumioprace volumio[6360]: info: Folder /tmp/plugins removed Jun 26 09:21:58 volumioprace volumio[6360]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 09:21:58 volumioprace volumio[6360]: Error: Unable to resolve or reject the same promise twice Jun 26 09:21:58 volumioprace volumio[6360]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 26 09:21:58 volumioprace volumio[6360]: at /volumio/app/pluginmanager.js:1087:11 Jun 26 09:21:58 volumioprace volumio[6360]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 26 09:21:58 volumioprace volumio[6360]: at FSReqCallback.oncomplete (fs.js:180:23) Jun 26 09:21:58 volumioprace volumio[6360]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 09:21:59 volumioprace sudo[7051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 09:20 Jun 26 09:21:59 volumioprace sudo[7051]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="ac8237291e9f6fb802f0ead66c5a8bb83ed22e68" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 19 Jun 2025 05:10:54 PM CEST" VOLUMIO_VERSION="3.817" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d1fbccf832f6f49fc2a0b57fd64ae923"