-- Logs begin at Wed 2026-08-26 17:55:54 CEST, end at Sun 2026-08-30 21:41:53 CEST. -- Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::getState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Aug 30 21:40:00 rivo volumio[3113]: info: Aug 30 21:40:00 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Aug 30 21:40:00 rivo volumio[3113]: info: Ignoring MPD Status Update Aug 30 21:40:00 rivo volumio[3113]: info: Aug 30 21:40:00 rivo volumio[3113]: ---------------------------- MPD announces state update: player Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::getState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Aug 30 21:40:00 rivo volumio[3113]: info: Aug 30 21:40:00 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Aug 30 21:40:00 rivo volumio[3113]: info: Ignoring MPD Status Update Aug 30 21:40:00 rivo volumio[3113]: info: Aug 30 21:40:00 rivo volumio[3113]: ---------------------------- MPD announces state update: player Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::getState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 9ms Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand status took 11 milliseconds Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 11ms Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand status took 9 milliseconds Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 8ms Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand status took 8 milliseconds Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseState Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 21:40:00 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Aug 30 21:40:00 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Aug 30 21:40:00 rivo volumio[3113]: info: CorePlayQueue::getTrack 1 Aug 30 21:40:00 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"7623021&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/7623021&soundQuality=HI_RES","trackType":"tidal"} Aug 30 21:40:00 rivo volumio[3113]: verbose: CURRENT POSITION 1 Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Aug 30 21:40:00 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Aug 30 21:40:00 rivo volumio[3113]: info: CorePlayQueue::getTrack 1 Aug 30 21:40:00 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"7623021&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/7623021&soundQuality=HI_RES","trackType":"tidal"} Aug 30 21:40:00 rivo volumio[3113]: verbose: CURRENT POSITION 1 Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Aug 30 21:40:00 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio[3113]: info: ControllerMpd::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Aug 30 21:40:00 rivo volumio[3113]: info: CorePlayQueue::getTrack 1 Aug 30 21:40:00 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"7623021&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/7623021&soundQuality=HI_RES","trackType":"tidal"} Aug 30 21:40:00 rivo volumio[3113]: verbose: CURRENT POSITION 1 Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Aug 30 21:40:00 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.045+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.046+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.050+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.051+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.052+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.053+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.053+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.054+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.055+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.056+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=350248 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.056+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.057+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623020 title="Symphony No. 93 in D Major, Hob. I:93: II. Largo cantabile" Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 69ms Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 68ms Aug 30 21:40:00 rivo volumio[3113]: info: ------------------------------ 66ms Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Signalling Playback active due to playback status change Aug 30 21:40:00 rivo volumio[3113]: info: Signalling Playback active due to playback status change Aug 30 21:40:00 rivo volumio[3113]: info: Signalling Playback active due to playback status change Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::startPlaybackTimer Aug 30 21:40:00 rivo volumio[3113]: info: CorePlayQueue::getTrack 2 Aug 30 21:40:00 rivo volumio[3113]: info: CoreStateMachine::pushState Aug 30 21:40:00 rivo volumio[3113]: info: CorePlayQueue::getTrack 2 Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:40:00 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.655+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" state=STATUS_PLAYING positionMs=500 volume=100 Aug 30 21:40:00 rivo volumio5-onboarding[4198]: time=2026-08-30T21:40:00.656+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.146:50318,00:00:00:00:00:00%1e @ 0x28d8510" id=tidal://song/7623021 title="Symphony No. 93 in D Major, Hob. I:93: III. Menuet" Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Updating RAAT Signal Path Aug 30 21:40:00 rivo volumio[3113]: info: Signalling Playback active due to playback status change Aug 30 21:40:04 rivo ntpd[32743]: 164.92.156.55 local addr 192.168.0.138 -> Aug 30 21:41:17 rivo volumio[3113]: Searching plugin music_service/tidal Aug 30 21:41:17 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 21:41:17 rivo volumio[3113]: info: searchTIDALUri took 442 milliseconds Aug 30 21:41:17 rivo volumio[3113]: info: search took 444 milliseconds Aug 30 21:41:17 rivo volumio[3113]: info: All search sources collected, pushing search results Aug 30 21:41:18 rivo volumio[3113]: Searching plugin music_service/tidal Aug 30 21:41:18 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 21:41:19 rivo volumio[3113]: info: searchTIDALUri took 444 milliseconds Aug 30 21:41:19 rivo volumio[3113]: info: search took 445 milliseconds Aug 30 21:41:19 rivo volumio[3113]: info: All search sources collected, pushing search results Aug 30 21:41:52 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Aug 30 21:41:52 rivo volumio[3113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:41:52 rivo volumio[3113]: TypeError: Cannot read property 'then' of undefined Aug 30 21:41:52 rivo volumio[3113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Aug 30 21:41:52 rivo volumio[3113]: at Socket.emit (events.js:400:28) Aug 30 21:41:52 rivo volumio[3113]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 21:41:52 rivo volumio[3113]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 30 21:41:52 rivo volumio[3113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:41:53 rivo sudo[7382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 21:40 Aug 30 21:41:53 rivo sudo[7382]: 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="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"