-- Logs begin at Fri 2025-06-27 15:13:44 +07, end at Fri 2025-06-27 15:53:32 +07. -- Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioPause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::stPlaybackTimer Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::servicePause Jun 27 15:52:04 rivoplus volumio[3428]: info: CorePlayQueue::getTrack 0 Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::servicePause Jun 27 15:52:04 rivoplus volumio[3428]: info: [1751014324932] ControllerUPNPBrowser::pause Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand pause Jun 27 15:52:04 rivoplus volumio[3428]: info: Jun 27 15:52:04 rivoplus volumio[3428]: ---------------------------- MPD announces state update: player Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand pause took 6 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::getState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand status Jun 27 15:52:04 rivoplus volumio[3428]: info: Jun 27 15:52:04 rivoplus volumio[3428]: ---------------------------- MPD announces state update: player Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::getState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand status Jun 27 15:52:04 rivoplus volumio[3428]: info: Jun 27 15:52:04 rivoplus volumio[3428]: ---------------------------- MPD announces state update: player Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::getState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand status Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand status took 8 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand status took 5 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand status took 5 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseState Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseTrackInfo Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseTrackInfo Jun 27 15:52:04 rivoplus volumio[3428]: verbose: ControllerMpd::parseTrackInfo Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::servicePushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CorePlayQueue::getTrack 0 Jun 27 15:52:04 rivoplus volumio[3428]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17743,"duration":274,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"9506.dsf","artist":null,"album":null,"uri":"http://192.168.1.2:50002/m/NDLNA/9506.dsf","trackType":"dsf"} Jun 27 15:52:04 rivoplus volumio[3428]: verbose: CURRENT POSITION 0 Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState stateService pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState currentStatus pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioPushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioGetState Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output update for this device Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::stPlaybackTimer Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::servicePushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CorePlayQueue::getTrack 0 Jun 27 15:52:04 rivoplus volumio[3428]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17743,"duration":274,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"9506.dsf","artist":null,"album":null,"uri":"http://192.168.1.2:50002/m/NDLNA/9506.dsf","trackType":"dsf"} Jun 27 15:52:04 rivoplus volumio[3428]: verbose: CURRENT POSITION 0 Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState stateService pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState currentStatus pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioPushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioGetState Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output update for this device Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::stPlaybackTimer Jun 27 15:52:04 rivoplus volumio[3428]: info: ControllerMpd::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::servicePushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CorePlayQueue::getTrack 0 Jun 27 15:52:04 rivoplus volumio[3428]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17743,"duration":274,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"9506.dsf","artist":null,"album":null,"uri":"http://192.168.1.2:50002/m/NDLNA/9506.dsf","trackType":"dsf"} Jun 27 15:52:04 rivoplus volumio[3428]: verbose: CURRENT POSITION 0 Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState stateService pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::syncState currentStatus pause Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::pushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioPushState Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreCommandRouter::volumioGetState Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output update for this device Jun 27 15:52:04 rivoplus volumio[3428]: info: MRS: Pushing multiroomSync output Jun 27 15:52:04 rivoplus volumio[3428]: info: CoreStateMachine::stPlaybackTimer Jun 27 15:52:05 rivoplus volumio[3428]: info: ------------------------------ 68ms Jun 27 15:52:05 rivoplus volumio[3428]: info: ------------------------------ 65ms Jun 27 15:52:05 rivoplus volumio[3428]: info: ------------------------------ 65ms Jun 27 15:52:05 rivoplus volumio[3428]: info: MCU Signalled Playback Inactive Jun 27 15:52:07 rivoplus volumio[3428]: info: Executing endpoint metavolumio Jun 27 15:52:07 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 15:52:23 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 15:52:23 rivoplus volumio[3428]: info: Preload queue cleared Jun 27 15:52:23 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9495 Jun 27 15:52:23 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9496 Jun 27 15:52:23 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9497 Jun 27 15:52:23 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9498 Jun 27 15:52:23 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9495 in service upnp_browser Jun 27 15:52:23 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9496 in service upnp_browser Jun 27 15:52:23 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9497 in service upnp_browser Jun 27 15:52:23 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9498 in service upnp_browser Jun 27 15:52:29 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 15:52:29 rivoplus volumio[3428]: info: Preload queue cleared Jun 27 15:52:45 rivoplus ntpd[12413]: Soliciting pool server 27.71.27.209 Jun 27 15:52:47 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 15:52:47 rivoplus volumio[3428]: info: Preload queue cleared Jun 27 15:52:48 rivoplus ntpd[12413]: Soliciting pool server 103.199.19.135 Jun 27 15:52:48 rivoplus ntpd[12413]: Soliciting pool server 27.71.27.209 Jun 27 15:52:54 rivoplus ntpd[12413]: Soliciting pool server 27.71.27.209 Jun 27 15:52:55 rivoplus volumio[3428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 27 15:52:55 rivoplus volumio[3428]: info: Preload queue cleared Jun 27 15:52:55 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9517 Jun 27 15:52:55 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9518 Jun 27 15:52:55 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9532 Jun 27 15:52:55 rivoplus volumio[3428]: info: Preloading song: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9476 Jun 27 15:52:55 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9517 in service upnp_browser Jun 27 15:52:55 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9518 in service upnp_browser Jun 27 15:52:55 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9532 in service upnp_browser Jun 27 15:52:56 rivoplus volumio[3428]: info: Exploding uri upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@9476 in service upnp_browser Jun 27 15:53:32 rivoplus volumio[3428]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 15:53:32 rivoplus volumio[3428]: Error: connect ETIMEDOUT 151.101.130.79:443 Jun 27 15:53:32 rivoplus volumio[3428]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 27 15:53:32 rivoplus volumio[3428]: errno: -110, Jun 27 15:53:32 rivoplus volumio[3428]: code: 'ETIMEDOUT', Jun 27 15:53:32 rivoplus volumio[3428]: syscall: 'connect', Jun 27 15:53:32 rivoplus volumio[3428]: address: '151.101.130.79', Jun 27 15:53:32 rivoplus volumio[3428]: port: 443 Jun 27 15:53:32 rivoplus volumio[3428]: } Jun 27 15:53:32 rivoplus volumio[3428]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 15:53:32 rivoplus sudo[14849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 15:52 Jun 27 15:53:32 rivoplus sudo[14849]: 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="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"