-- Logs begin at Fri 2026-02-27 17:43:28 GMT, end at Mon 2026-04-13 18:17:53 BST. -- Apr 13 18:16:03 rivo volumio[3105]: info: MCU Signalled Sleep Mode Active Apr 13 18:16:03 rivo sudo[13360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force off Apr 13 18:16:03 rivo sudo[13360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:16:03 rivo sudo[13360]: pam_unix(sudo:session): session closed for user root Apr 13 18:17:47 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:47.097+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.233:56639 Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.288+01:00 level=INFO msg="player play request" component=server type=REQUEST_TYPE_PLAYER_PLAY peer="192.168.1.233:56639 @ 0x28004b0" latency=16.154906ms timeout=10s Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::volumioPlay Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::play index undefined Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::startPlaybackTimer Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 13 18:17:52 rivo volumio[3105]: verbose: ControllerMpd::sendMpdCommand play Apr 13 18:17:52 rivo volumio[3105]: info: Apr 13 18:17:52 rivo volumio[3105]: ---------------------------- MPD announces state update: player Apr 13 18:17:52 rivo volumio[3105]: info: sendMpdCommand play took 36 milliseconds Apr 13 18:17:52 rivo volumio[3105]: info: ControllerMpd::getState Apr 13 18:17:52 rivo volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::servicePushState Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: verbose: STATE SERVICE {"status":"play","seek":155998} Apr 13 18:17:52 rivo volumio[3105]: verbose: CURRENT POSITION 0 Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::syncState stateService play Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::syncState currentStatus pause Apr 13 18:17:52 rivo volumio[3105]: info: CoreStateMachine::pushState Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::volumioPushState Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::volumioGetState Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:17:52 rivo volumio[3105]: info: MRS: Pushing multiroomSync output Apr 13 18:17:52 rivo volumio[3105]: info: [1776100672355] [RadioParadise] getMetadata started with url undefined Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.364+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.233:56639 @ 0x28004b0" state=STATUS_PLAYING positionMs=156251 volume=52 Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.365+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.233:56639 @ 0x28004b0" id=http://stream-uk1.radioparadise.com/aac-320 title="Radio Paradise (AAC 320k)" Apr 13 18:17:52 rivo volumio[3105]: info: sendMpdCommand status took 37 milliseconds Apr 13 18:17:52 rivo volumio[3105]: verbose: ControllerMpd::parseState Apr 13 18:17:52 rivo volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 18:17:52 rivo volumio[3105]: info: Signalling Playback active due to playback status change Apr 13 18:17:52 rivo volumio[3105]: info: Updating RAAT Signal Path Apr 13 18:17:52 rivo volumio[3105]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 13 18:17:52 rivo volumio[3105]: verbose: ControllerMpd::parseTrackInfo Apr 13 18:17:52 rivo volumio[3105]: info: ControllerMpd::pushState Apr 13 18:17:52 rivo volumio[3105]: info: CoreCommandRouter::servicePushState Apr 13 18:17:52 rivo volumio[3105]: info: CorePlayQueue::getTrack 0 Apr 13 18:17:52 rivo volumio[3105]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 13 18:17:52 rivo volumio[3105]: info: ------------------------------ 78ms Apr 13 18:17:52 rivo volumio[3105]: info: [1776100672410] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Apr 13 18:17:52 rivo volumio[3105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 18:17:52 rivo volumio[3105]: TypeError: Cannot read property 'toUpperCase' of undefined Apr 13 18:17:52 rivo volumio[3105]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Apr 13 18:17:52 rivo volumio[3105]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Apr 13 18:17:52 rivo volumio[3105]: at ClientRequest.emit (events.js:400:28) Apr 13 18:17:52 rivo volumio[3105]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Apr 13 18:17:52 rivo volumio[3105]: at TLSSocket.emit (events.js:400:28) Apr 13 18:17:52 rivo volumio[3105]: at emitErrorNT (internal/streams/destroy.js:106:8) Apr 13 18:17:52 rivo volumio[3105]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Apr 13 18:17:52 rivo volumio[3105]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 13 18:17:52 rivo volumio[3105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.586+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.233:56639 Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.587+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.233:56639 Apr 13 18:17:52 rivo volumio5-onboarding[3993]: time=2026-04-13T18:17:52.598+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.233:56642 Apr 13 18:17:53 rivo sudo[13641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 18:16 Apr 13 18:17:53 rivo sudo[13641]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:38:48 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8e381701610c2a79deb52e712150c089"