-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Fri 2024-07-12 11:45:21 JST. -- Jul 12 11:44:02 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:44:06 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:44:12 rivo nmbd[3045]: [2024/07/12 11:44:12.261529, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 12 11:44:12 rivo nmbd[3045]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.30 for name WORKGROUP<1d>. Jul 12 11:44:12 rivo nmbd[3045]: This response was from IP 192.168.1.19, reporting an IP address of 192.168.1.19. Jul 12 11:44:33 rivo volumio[3521]: info: MRS: Found cast device: JBL-Bar-5.0-c0f5ecd6c6cbb412eecb579303e0a4d3 Jul 12 11:44:54 rivo volumio[3521]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.21 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:44:54 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: Retrieving Cloud Streaming UI Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 11:44:54 rivo volumio[3521]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 12 11:44:54 rivo volumio[3521]: info: Received Get System Info Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:44:54 rivo volumio[3521]: info: Discovery: Getting this device information Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:44:54 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:44:54 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:44:54 rivo volumio[3521]: info: Listing playlists Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 12 11:44:54 rivo volumio[3521]: info: Getting Tidal Cloud Configuration Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: Getting Qobuz Cloud Configuration Jul 12 11:44:54 rivo volumio[3521]: info: Asking plugin for UI Config Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: Getting Spotify Cloud Configuration Jul 12 11:44:54 rivo volumio[3521]: info: Asking plugin for UI Config Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: Saving Spotify Acccount Jul 12 11:44:54 rivo volumio[3521]: info: Got it Jul 12 11:44:54 rivo volumio[3521]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 12 11:44:54 rivo volumio[3521]: info: Got Tidal Cloud Configuration Jul 12 11:44:54 rivo volumio[3521]: info: Got it Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 11:44:54 rivo volumio[3521]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 11:44:55 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:44:55 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 12 11:44:56 rivo volumio[3521]: info: Received Get System Info Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:44:56 rivo volumio[3521]: info: Discovery: Getting this device information Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:44:56 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:44:56 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:44:57 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 12 11:44:57 rivo volumio[3521]: info: Received Get System Info Jul 12 11:44:57 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:44:57 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:44:57 rivo volumio[3521]: info: Discovery: Getting this device information Jul 12 11:44:57 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:44:57 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:44:57 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:44:59 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:03 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:04 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand rescan Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: Jul 12 11:45:06 rivo volumio[3521]: ---------------------------- MPD announces state update: update Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand rescan took 7 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::getState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: Jul 12 11:45:06 rivo volumio[3521]: ---------------------------- MPD announces state update: update Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 7 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::getState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 6 milliseconds Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: info: Command Router : Notfying DB Updatetrue Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: Jul 12 11:45:06 rivo volumio[3521]: ---------------------------- MPD announces state update: update Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::getState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 60 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 57 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 5 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 4 milliseconds Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: info: Command Router : Notfying DB Updatefalse Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::Close All Modals sent Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: info: Command Router : Notfying DB Updatefalse Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::Close All Modals sent Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::servicePushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreStateMachine::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::volumioPushState Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output update for this device Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 12 11:45:06 rivo volumio[3521]: info: ------------------------------ 180ms Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: Jul 12 11:45:06 rivo volumio[3521]: ---------------------------- MPD announces state update: update Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::getState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand playlistinfo took 91 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand playlistinfo took 53 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 20 milliseconds Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand status took 19 milliseconds Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: info: Command Router : Notfying DB Updatefalse Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::Close All Modals sent Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseState Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::servicePushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreStateMachine::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::volumioPushState Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output update for this device Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::servicePushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreStateMachine::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::volumioPushState Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output update for this device Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 12 11:45:06 rivo volumio[3521]: info: ------------------------------ 307ms Jul 12 11:45:06 rivo volumio[3521]: info: ------------------------------ 251ms Jul 12 11:45:06 rivo volumio[3521]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 12 11:45:06 rivo volumio[3521]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:45:06 rivo volumio[3521]: info: ControllerMpd::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::servicePushState Jul 12 11:45:06 rivo volumio[3521]: info: CoreStateMachine::pushState Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: CoreCommandRouter::volumioPushState Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output update for this device Jul 12 11:45:06 rivo volumio[3521]: info: MRS: Pushing multiroomSync output Jul 12 11:45:06 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:06 rivo volumio[3521]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 12 11:45:06 rivo volumio[3521]: info: ------------------------------ 160ms Jul 12 11:45:07 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:11 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:11 rivo volumio[3521]: info: MRS: Found cast device: JBL-Bar-5.0-c0f5ecd6c6cbb412eecb579303e0a4d3 Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 12 11:45:13 rivo volumio[3521]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 12 11:45:13 rivo volumio[3521]: info: Received Get System Version Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 11:45:13 rivo volumio[3521]: info: Received Get System Info Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:45:13 rivo volumio[3521]: info: Discovery: Getting this device information Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::volumioGetState Jul 12 11:45:13 rivo volumio[3521]: info: CorePlayQueue::getTrack 0 Jul 12 11:45:13 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:45:15 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:15 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 12 11:45:15 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 12 11:45:19 rivo volumio[3521]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 11:45:20 rivo volumio[3521]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 11:45:20 rivo volumio[3521]: Error: dns service error: unknown Jul 12 11:45:20 rivo volumio[3521]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 12 11:45:20 rivo volumio[3521]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 12 11:45:20 rivo volumio[3521]: errorCode: -65537 Jul 12 11:45:20 rivo volumio[3521]: } Jul 12 11:45:20 rivo volumio[3521]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 11:45:21 rivo sudo[5910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 11:44 Jul 12 11:45:21 rivo sudo[5910]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:27:25 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="891b8adfc94c7cc44424972b60e8e09c"