-- Logs begin at Wed 2025-05-28 20:23:04 CEST, end at Wed 2025-05-28 20:37:13 CEST. -- May 28 20:36:08 rivo volumio[3191]: info: CorePlayQueue::getTrack 0 May 28 20:36:08 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:08 rivo volumio[3191]: info: Prefetching next song May 28 20:36:08 rivo volumio[3191]: info: [1748457368221] ControllerQobuz::prefetch May 28 20:36:08 rivo volumio[3191]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/8766756" May 28 20:36:08 rivo volumio[3191]: error: MPD error: Error: This socket has been ended by the other party May 28 20:36:08 rivo volumio[3191]: error: This socket has been ended by the other party {"code":"EPIPE"} May 28 20:36:08 rivo volumio[3191]: error: MPD error: Error: This socket has been ended by the other party May 28 20:36:08 rivo volumio[3191]: error: This socket has been ended by the other party {"code":"EPIPE"} May 28 20:36:08 rivo volumio[3191]: error: MPD error: Error: This socket has been ended by the other party May 28 20:36:08 rivo volumio[3191]: error: This socket has been ended by the other party {"code":"EPIPE"} May 28 20:36:12 rivo volumio[3191]: info: CoreStateMachine::startPlaybackTimer May 28 20:36:12 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:13 rivo volumio[3191]: info: CoreStateMachine::pushState May 28 20:36:13 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:13 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:36:13 rivo volumio[3191]: info: CoreCommandRouter::volumioPushState May 28 20:36:13 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:13 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:15 rivo volumio[3191]: info: Tunnel connection is inactive, restarting it May 28 20:36:15 rivo volumio[3191]: info: Starting Tunnel 1 May 28 20:36:15 rivo volumio[3191]: info: Starting Tunnel Connection Checker May 28 20:36:15 rivo sudo[7031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 28 20:36:15 rivo sudo[7031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:36:15 rivo systemd[1]: Started MyVolumio SSH Tunnel. May 28 20:36:15 rivo sudo[7031]: pam_unix(sudo:session): session closed for user root May 28 20:36:15 rivo volumio[3191]: info: Remote SSH Started May 28 20:36:15 rivo autossh[7035]: port set to 0, monitoring disabled May 28 20:36:15 rivo autossh[7035]: starting ssh (count 1) May 28 20:36:15 rivo autossh[7035]: ssh child pid is 7048 May 28 20:36:15 rivo volumio[3191]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 28 20:36:15 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:15 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:15 rivo volumiossh-tunnel[7033]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. May 28 20:36:54 rivo volumio[3191]: verbose: New Socket.io Connection to 192.168.1.63 from 192.168.1.28 UA: Mozilla/5.0 (Linux; Android 10; PCT-L29 Build/HUAWEIPCT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 20:36:54 rivo volumio[3191]: info: Received Get System Info May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:36:54 rivo volumio[3191]: info: Discovery: Getting this device information May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetVisibleSources May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 20:36:54 rivo volumio[3191]: info: Received Get System Info May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:36:54 rivo volumio[3191]: info: Discovery: Getting this device information May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetState May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getTrack 1 May 28 20:36:54 rivo volumio[3191]: info: Listing playlists May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::volumioGetQueue May 28 20:36:54 rivo volumio[3191]: info: CoreStateMachine::getQueue May 28 20:36:54 rivo volumio[3191]: info: CorePlayQueue::getQueue May 28 20:36:54 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 20:36:56 rivo volumio[3191]: verbose: New Socket.io Connection to 192.168.1.63:3000 from 192.168.1.28 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 28 20:37:03 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 20:37:04 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 20:37:09 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 28 20:37:09 rivo volumio[3191]: info: MyVolumio login type: Token May 28 20:37:10 rivo volumio[3191]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 28 20:37:10 rivo volumio[3191]: info: MyVolumio token set successfully May 28 20:37:10 rivo volumio[3191]: info: MYVOLUMIO: Adding device May 28 20:37:10 rivo volumio[3191]: info: MYVOLUMIO: Evaluating Server May 28 20:37:11 rivo volumio[3191]: info: MyVolumio Plan changed: superstar May 28 20:37:11 rivo volumio[3191]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar May 28 20:37:11 rivo volumio[3191]: info: Adding audio output: May 28 20:37:11 rivo volumio[3191]: error: Can't add: May 28 20:37:11 rivo volumio[3191]: info: MYVOLUMIO: Adding device May 28 20:37:11 rivo volumio[3191]: info: MYVOLUMIO: Evaluating Server May 28 20:37:11 rivo volumio[3191]: info: Remote config written successfully May 28 20:37:11 rivo volumio[3191]: info: Starting Tunnel 1 May 28 20:37:11 rivo volumio[3191]: info: Starting Tunnel Connection Checker May 28 20:37:12 rivo volumio[3191]: info: MYVolumio Device enabled May 28 20:37:12 rivo volumio[3191]: info: MyVolumio status changed May 28 20:37:12 rivo volumio[3191]: info: Streaming services startup May 28 20:37:12 rivo volumio[3191]: info: Starting Streaming Daemon May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 28 20:37:12 rivo volumio[3191]: info: Setting Geolocation for MyVolumio to eu7 May 28 20:37:12 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:12 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:12 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:12 rivo sudo[7357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 20:37:12 rivo sudo[7357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:37:12 rivo sudo[7357]: pam_unix(sudo:session): session closed for user root May 28 20:37:12 rivo volumio[3191]: error: Cannot start Volumio Streaming Daemon May 28 20:37:12 rivo volumio[3191]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 20:37:12 rivo volumio[3191]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 20:37:12 rivo volumio[3191]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo sudo[7361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 28 20:37:12 rivo volumio[3191]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 28 20:37:12 rivo sudo[7361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:37:12 rivo volumio[3191]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:37:12 rivo volumio[3191]: Error: Unable to resolve or reject the same promise twice May 28 20:37:12 rivo volumio[3191]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 28 20:37:12 rivo volumio[3191]: at Array. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5676) May 28 20:37:12 rivo volumio[3191]: at Promise.reject (/volumio/node_modules/kew/kew.js:204:26) May 28 20:37:12 rivo volumio[3191]: at nextTickCallback (/volumio/node_modules/kew/kew.js:49:11) May 28 20:37:12 rivo volumio[3191]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 28 20:37:12 rivo volumio[3191]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:37:12 rivo autossh[7035]: received signal to exit (15) May 28 20:37:12 rivo systemd[1]: Stopping MyVolumio SSH Tunnel... May 28 20:37:12 rivo systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 28 20:37:12 rivo systemd[1]: sshtunnel.service: Succeeded. May 28 20:37:12 rivo systemd[1]: Stopped MyVolumio SSH Tunnel. May 28 20:37:12 rivo systemd[1]: Started MyVolumio SSH Tunnel. May 28 20:37:12 rivo sudo[7361]: pam_unix(sudo:session): session closed for user root May 28 20:37:12 rivo autossh[7373]: port set to 0, monitoring disabled May 28 20:37:12 rivo autossh[7373]: starting ssh (count 1) May 28 20:37:12 rivo autossh[7373]: ssh child pid is 7382 May 28 20:37:12 rivo volumiossh-tunnel[7364]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. May 28 20:37:13 rivo sudo[7386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 20:36 May 28 20:37:13 rivo sudo[7386]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"