-- Logs begin at Sat 2025-07-19 10:24:56 +07, end at Sat 2025-07-19 14:16:07 +07. -- Jul 19 14:15:03 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:03 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:03 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:03 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 14:15:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 14:15:20 rivoplus volumio[3414]: info: Discovery: Getting this device information Jul 19 14:15:20 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jul 19 14:15:20 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 14:15:36 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 19 14:15:37 rivoplus volumio[3414]: info: browseTIDALUri took 283 milliseconds Jul 19 14:15:37 rivoplus volumio[3414]: info: Preload queue cleared Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:37 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:39 rivoplus ntpd[11748]: Soliciting pool server 2401:5b60:0:2::21 Jul 19 14:15:39 rivoplus ntpd[11748]: Soliciting pool server 27.71.27.209 Jul 19 14:15:42 rivoplus ntpd[11748]: Soliciting pool server 103.184.124.254 Jul 19 14:15:43 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:43 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:44 rivoplus volumio[3414]: info: Executing endpoint metavolumio Jul 19 14:15:44 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 14:15:44 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:44 rivoplus ntpd[11748]: Soliciting pool server 103.184.124.254 Jul 19 14:15:50 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:50 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 14:15:50 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 14:15:50 rivoplus volumio[3414]: info: Discovery: Getting this device information Jul 19 14:15:50 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jul 19 14:15:50 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 14:15:51 rivoplus volumio[3414]: error: Failed request for metavolumio API Jul 19 14:15:58 rivoplus kernel: usb 1-1.4.2: USB disconnect, device number 5 Jul 19 14:15:58 rivoplus kernel: usb 1-1.4.2: cannot submit urb (err = -19) Jul 19 14:15:58 rivoplus volumio[3414]: info: Jul 19 14:15:58 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::getState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jul 19 14:15:58 rivoplus volumio[3414]: info: Jul 19 14:15:58 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::getState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jul 19 14:15:58 rivoplus volumio[3414]: info: Jul 19 14:15:58 rivoplus volumio[3414]: ---------------------------- MPD announces state update: player Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::getState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand status Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand status took 13 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand status took 12 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand status took 11 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseState Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand clearerror took 20 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand clearerror took 18 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand clearerror took 18 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jul 19 14:15:58 rivoplus volumio[3414]: verbose: ControllerMpd::parseTrackInfo Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jul 19 14:15:58 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85202,"duration":428,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3301 Kbps","isStreaming":false,"title":"196421286&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/196421286&soundQuality=HI_RES","trackType":"tidal"} Jul 19 14:15:58 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService pause Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::stPlaybackTimer Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jul 19 14:15:58 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85202,"duration":428,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3301 Kbps","isStreaming":false,"title":"196421286&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/196421286&soundQuality=HI_RES","trackType":"tidal"} Jul 19 14:15:58 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService pause Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::stPlaybackTimer Jul 19 14:15:58 rivoplus volumio[3414]: info: ControllerMpd::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::servicePushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CorePlayQueue::getTrack 0 Jul 19 14:15:58 rivoplus volumio[3414]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85202,"duration":428,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3301 Kbps","isStreaming":false,"title":"196421286&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/196421286&soundQuality=HI_RES","trackType":"tidal"} Jul 19 14:15:58 rivoplus volumio[3414]: verbose: CURRENT POSITION 0 Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState stateService pause Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::syncState currentStatus play Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::pushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioPushState Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::volumioGetState Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output update for this device Jul 19 14:15:58 rivoplus volumio[3414]: info: MRS: Pushing multiroomSync output Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreStateMachine::stPlaybackTimer Jul 19 14:15:58 rivoplus volumio[3414]: info: ------------------------------ 81ms Jul 19 14:15:58 rivoplus volumio[3414]: info: ------------------------------ 81ms Jul 19 14:15:58 rivoplus volumio[3414]: info: ------------------------------ 80ms Jul 19 14:15:58 rivoplus volumio[3414]: info: Jul 19 14:15:58 rivoplus volumio[3414]: ---------------------------- USB Audio Device Detached Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 14:15:58 rivoplus volumio[3414]: No protocol specified Jul 19 14:15:58 rivoplus volumio[3414]: xcb_connection_has_error() returned true Jul 19 14:15:58 rivoplus volumio[3414]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 19 14:15:58 rivoplus volumio[3414]: info: Stopping RAAT Plugin Jul 19 14:15:58 rivoplus volumio[3414]: info: MCU Signalled Playback Inactive Jul 19 14:15:58 rivoplus sudo[13340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Jul 19 14:15:58 rivoplus sudo[13340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 14:15:58 rivoplus systemd[1]: Stopping RAAT DAEMON... Jul 19 14:15:58 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 14:15:58 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jul 19 14:15:58 rivoplus systemd[1]: Stopped RAAT DAEMON. Jul 19 14:15:58 rivoplus volumio[3414]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 14:15:58 rivoplus sudo[13340]: pam_unix(sudo:session): session closed for user root Jul 19 14:15:58 rivoplus volumio[3414]: info: Raat Daemon stopped successfully Jul 19 14:16:07 rivoplus volumio[3414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 14:16:07 rivoplus volumio[3414]: Error: connect ETIMEDOUT 151.101.130.79:443 Jul 19 14:16:07 rivoplus volumio[3414]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 19 14:16:07 rivoplus volumio[3414]: errno: -110, Jul 19 14:16:07 rivoplus volumio[3414]: code: 'ETIMEDOUT', Jul 19 14:16:07 rivoplus volumio[3414]: syscall: 'connect', Jul 19 14:16:07 rivoplus volumio[3414]: address: '151.101.130.79', Jul 19 14:16:07 rivoplus volumio[3414]: port: 443 Jul 19 14:16:07 rivoplus volumio[3414]: } Jul 19 14:16:07 rivoplus volumio[3414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 14:16:07 rivoplus sudo[13370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-19 14:15 Jul 19 14:16:07 rivoplus sudo[13370]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:55:44 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="66719bc94798fca30ca68a8e6d955a83"