-- Logs begin at Wed 2026-02-11 05:03:15 CET, end at Wed 2026-02-11 23:11:32 CET. -- Feb 11 23:10:00 rivoplus go-librespot[30499]: time="2026-02-11T23:10:00+01:00" level=trace msg="sent dealer ping" Feb 11 23:10:00 rivoplus go-librespot[30499]: time="2026-02-11T23:10:00+01:00" level=trace msg="received dealer pong" Feb 11 23:10:03 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:07 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:11 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:15 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::sendMpdCommand update Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:10:19 rivoplus volumio[3404]: info: Feb 11 23:10:19 rivoplus volumio[3404]: ---------------------------- MPD announces state update: update Feb 11 23:10:19 rivoplus volumio[3404]: info: sendMpdCommand update took 10 milliseconds Feb 11 23:10:19 rivoplus volumio[3404]: info: ControllerMpd::getState Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:10:19 rivoplus volumio[3404]: info: Feb 11 23:10:19 rivoplus volumio[3404]: ---------------------------- MPD announces state update: update Feb 11 23:10:19 rivoplus volumio[3404]: info: ControllerMpd::getState Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:10:19 rivoplus volumio[3404]: info: sendMpdCommand status took 21 milliseconds Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::parseState Feb 11 23:10:19 rivoplus volumio[3404]: info: Command Router : Notfying DB Updatetrue Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::Close All Modals sent Feb 11 23:10:19 rivoplus volumio[3404]: info: sendMpdCommand status took 73 milliseconds Feb 11 23:10:19 rivoplus volumio[3404]: info: sendMpdCommand status took 72 milliseconds Feb 11 23:10:19 rivoplus volumio[3404]: info: sendMpdCommand status took 71 milliseconds Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::parseState Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::parseState Feb 11 23:10:19 rivoplus volumio[3404]: info: Command Router : Notfying DB Updatetrue Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::Close All Modals sent Feb 11 23:10:19 rivoplus volumio[3404]: verbose: ControllerMpd::parseState Feb 11 23:10:19 rivoplus volumio[3404]: info: ControllerMpd::pushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::servicePushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreStateMachine::pushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::volumioPushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::volumioGetState Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: MRS: Pushing multiroomSync output update for this device Feb 11 23:10:19 rivoplus volumio[3404]: info: MRS: Pushing multiroomSync output Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 23:10:19 rivoplus volumio5-onboarding[3667]: time=2026-02-11T23:10:19.738+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.116:57048,192.168.1.116:57155 @ 0x31369f0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 23:10:19 rivoplus volumio5-onboarding[3667]: time=2026-02-11T23:10:19.739+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.116:57048,192.168.1.116:57155 @ 0x31369f0" id=https://ice2.somafm.com/groovesalad-256-mp3 title="Soma FM Groove Salad 256kb" Feb 11 23:10:19 rivoplus volumio[3404]: info: ControllerMpd::pushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::servicePushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreStateMachine::pushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::volumioPushState Feb 11 23:10:19 rivoplus volumio[3404]: info: CoreCommandRouter::volumioGetState Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: MRS: Pushing multiroomSync output update for this device Feb 11 23:10:19 rivoplus volumio[3404]: info: MRS: Pushing multiroomSync output Feb 11 23:10:19 rivoplus volumio[3404]: info: CorePlayQueue::getTrack 0 Feb 11 23:10:19 rivoplus volumio[3404]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 23:10:19 rivoplus volumio[3404]: info: ------------------------------ 172ms Feb 11 23:10:19 rivoplus volumio5-onboarding[3667]: time=2026-02-11T23:10:19.761+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.116:57048,192.168.1.116:57155 @ 0x31369f0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 23:10:19 rivoplus volumio5-onboarding[3667]: time=2026-02-11T23:10:19.762+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.116:57048,192.168.1.116:57155 @ 0x31369f0" id=https://ice2.somafm.com/groovesalad-256-mp3 title="Soma FM Groove Salad 256kb" Feb 11 23:10:19 rivoplus volumio[3404]: info: ------------------------------ 174ms Feb 11 23:10:19 rivoplus volumio[3404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 11 23:10:19 rivoplus volumio[3404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 11 23:10:23 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:27 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:30 rivoplus go-librespot[30499]: time="2026-02-11T23:10:30+01:00" level=trace msg="sent dealer ping" Feb 11 23:10:30 rivoplus go-librespot[30499]: time="2026-02-11T23:10:30+01:00" level=trace msg="received dealer pong" Feb 11 23:10:31 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:35 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:39 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:43 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:45 rivoplus go-librespot[30499]: time="2026-02-11T23:10:45+01:00" level=trace msg="received accesspoint ping" Feb 11 23:10:45 rivoplus go-librespot[30499]: time="2026-02-11T23:10:45+01:00" level=trace msg="received accesspoint pong ack" Feb 11 23:10:47 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:51 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:55 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:10:59 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:00 rivoplus go-librespot[30499]: time="2026-02-11T23:11:00+01:00" level=trace msg="sent dealer ping" Feb 11 23:11:00 rivoplus go-librespot[30499]: time="2026-02-11T23:11:00+01:00" level=trace msg="received dealer pong" Feb 11 23:11:03 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:07 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:11 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:15 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:19 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:23 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:26 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 11 23:11:26 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 11 23:11:27 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:28 rivoplus volumio[3404]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L DS712P.local. -m SMB3_11 Feb 11 23:11:28 rivoplus volumio[3404]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L DS923P.local. -m SMB3_11 Feb 11 23:11:29 rivoplus volumio[3404]: info: Error browsing device DS712P for shares: Command failed: smbclient --no-pass --debuglevel=0 -L DS712P.local. -m SMB3_11 Feb 11 23:11:29 rivoplus volumio[3404]: Unable to initialize messaging context Feb 11 23:11:29 rivoplus volumio[3404]: info: Error browsing device DS923P for shares: Command failed: smbclient --no-pass --debuglevel=0 -L DS923P.local. -m SMB3_11 Feb 11 23:11:29 rivoplus volumio[3404]: Unable to initialize messaging context Feb 11 23:11:30 rivoplus go-librespot[30499]: time="2026-02-11T23:11:30+01:00" level=trace msg="sent dealer ping" Feb 11 23:11:30 rivoplus go-librespot[30499]: time="2026-02-11T23:11:30+01:00" level=trace msg="received dealer pong" Feb 11 23:11:31 rivoplus volumio[3404]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 23:11:31 rivoplus volumio[3404]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:11:31 rivoplus volumio[3404]: Error: dns service error: unknown Feb 11 23:11:31 rivoplus volumio[3404]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 11 23:11:31 rivoplus volumio[3404]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 11 23:11:31 rivoplus volumio[3404]: errorCode: -65537 Feb 11 23:11:31 rivoplus volumio[3404]: } Feb 11 23:11:31 rivoplus volumio[3404]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:11:32 rivoplus sudo[2331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 23:10 Feb 11 23:11:32 rivoplus sudo[2331]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 02:06:11 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="6fcef4b36db553cbf88901935dbe83e3"