-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Tue 2025-10-28 17:43:23 KST. -- Oct 28 17:42:09 volumio go-librespot[1952]: time="2025-10-28T17:42:09+09:00" level=trace msg="sent dealer ping" Oct 28 17:42:09 volumio go-librespot[1952]: time="2025-10-28T17:42:09+09:00" level=trace msg="received dealer pong" Oct 28 17:42:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 17:42:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 17:42:12 volumio volumio[1690]: info: Discovery: Getting this device information Oct 28 17:42:12 volumio volumio[1690]: info: CoreCommandRouter::volumioGetState Oct 28 17:42:12 volumio volumio[1690]: info: CorePlayQueue::getTrack 6 Oct 28 17:42:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 17:42:13 volumio volumio[1690]: info: CorePlayQueue::getTrack 6 Oct 28 17:42:13 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:42:13 volumio volumio[1690]: info: Prefetching next song Oct 28 17:42:13 volumio volumio[1690]: info: [1761640933481] ControllerSpotify::prefetch Oct 28 17:42:13 volumio volumio[1690]: info: Sending Spotify command with payload to local API: /player/add_to_queue Oct 28 17:42:13 volumio go-librespot[1952]: time="2025-10-28T17:42:13+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 28 17:42:17 volumio volumio[1690]: info: CoreStateMachine::startPlaybackTimer Oct 28 17:42:17 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:42:18 volumio volumio[1690]: info: CoreStateMachine::pushState Oct 28 17:42:18 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:42:18 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 17:42:18 volumio volumio[1690]: info: CoreCommandRouter::volumioPushState Oct 28 17:42:18 volumio volumio[1690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Oct 28 17:42:18 volumio volumio[1690]: SPOTIFY: SPOTIFY VOLUME 47 Oct 28 17:42:18 volumio volumio[1690]: SPOTIFY: VOLUMIO VOLUME 48 Oct 28 17:42:18 volumio volumio[1690]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 28 17:42:38 volumio go-librespot[1952]: time="2025-10-28T17:42:38+09:00" level=trace msg="sent dealer ping" Oct 28 17:42:39 volumio go-librespot[1952]: time="2025-10-28T17:42:39+09:00" level=trace msg="received dealer pong" Oct 28 17:42:42 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 17:42:42 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 17:42:42 volumio volumio[1690]: info: Discovery: Getting this device information Oct 28 17:42:42 volumio volumio[1690]: info: CoreCommandRouter::volumioGetState Oct 28 17:42:42 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:42:42 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: info: Retrieving Cloud Streaming UI Oct 28 17:42:53 volumio volumio[1690]: info: Getting Tidal Cloud Configuration Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: info: Getting Qobuz Cloud Configuration Oct 28 17:42:53 volumio volumio[1690]: info: Asking plugin for UI Config Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: info: Getting Spotify Cloud Configuration Oct 28 17:42:53 volumio volumio[1690]: info: Asking plugin for UI Config Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: info: Saving Spotify Acccount Oct 28 17:42:53 volumio volumio[1690]: info: Got Tidal Cloud Configuration Oct 28 17:42:53 volumio volumio[1690]: info: Got it Oct 28 17:42:53 volumio volumio[1690]: info: Got it Oct 28 17:42:53 volumio volumio[1690]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 17:42:53 volumio volumio[1690]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:42:53 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 28 17:42:57 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:01 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:02 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 28 17:43:02 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand update Oct 28 17:43:02 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand status Oct 28 17:43:02 volumio volumio[1690]: info: Oct 28 17:43:02 volumio volumio[1690]: ---------------------------- MPD announces state update: update Oct 28 17:43:02 volumio volumio[1690]: info: sendMpdCommand update took 2 milliseconds Oct 28 17:43:02 volumio volumio[1690]: info: ControllerMpd::getState Oct 28 17:43:02 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand status Oct 28 17:43:02 volumio volumio[1690]: info: sendMpdCommand status took 2 milliseconds Oct 28 17:43:02 volumio volumio[1690]: info: sendMpdCommand status took 0 milliseconds Oct 28 17:43:02 volumio volumio[1690]: verbose: ControllerMpd::parseState Oct 28 17:43:03 volumio volumio[1690]: info: Command Router : Notfying DB Updatetrue Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::parseState Oct 28 17:43:03 volumio volumio[1690]: info: ControllerMpd::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::servicePushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreStateMachine::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::volumioPushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Oct 28 17:43:03 volumio volumio[1690]: info: ------------------------------ 47ms Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: SPOTIFY VOLUME 47 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 28 17:43:03 volumio volumio[1690]: info: MPD Database updated - AlbumList cache refreshed Oct 28 17:43:03 volumio volumio[1690]: info: Oct 28 17:43:03 volumio volumio[1690]: ---------------------------- MPD announces state update: database Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand status Oct 28 17:43:03 volumio volumio[1690]: info: Oct 28 17:43:03 volumio volumio[1690]: ---------------------------- MPD announces state update: update Oct 28 17:43:03 volumio volumio[1690]: info: ControllerMpd::getState Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand status Oct 28 17:43:03 volumio volumio[1690]: info: ControllerMpd::getState Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::sendMpdCommand status Oct 28 17:43:03 volumio volumio[1690]: info: sendMpdCommand status took 3 milliseconds Oct 28 17:43:03 volumio volumio[1690]: info: sendMpdCommand status took 3 milliseconds Oct 28 17:43:03 volumio volumio[1690]: info: sendMpdCommand status took 3 milliseconds Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::parseState Oct 28 17:43:03 volumio volumio[1690]: info: Command Router : Notfying DB Updatefalse Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::Close All Modals sent Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::parseState Oct 28 17:43:03 volumio volumio[1690]: verbose: ControllerMpd::parseState Oct 28 17:43:03 volumio volumio[1690]: info: ControllerMpd::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::servicePushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreStateMachine::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::volumioPushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Oct 28 17:43:03 volumio volumio[1690]: info: ControllerMpd::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::servicePushState Oct 28 17:43:03 volumio volumio[1690]: info: CoreStateMachine::pushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: CoreCommandRouter::volumioPushState Oct 28 17:43:03 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:03 volumio volumio[1690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Oct 28 17:43:03 volumio volumio[1690]: info: ------------------------------ 40ms Oct 28 17:43:03 volumio volumio[1690]: info: ------------------------------ 40ms Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: SPOTIFY VOLUME 47 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: SPOTIFY VOLUME 47 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: VOLUMIO VOLUME 48 Oct 28 17:43:03 volumio volumio[1690]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 28 17:43:05 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:08 volumio go-librespot[1952]: time="2025-10-28T17:43:08+09:00" level=trace msg="sent dealer ping" Oct 28 17:43:09 volumio go-librespot[1952]: time="2025-10-28T17:43:09+09:00" level=trace msg="received dealer pong" Oct 28 17:43:09 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 17:43:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 17:43:12 volumio volumio[1690]: info: Discovery: Getting this device information Oct 28 17:43:12 volumio volumio[1690]: info: CoreCommandRouter::volumioGetState Oct 28 17:43:12 volumio volumio[1690]: info: CorePlayQueue::getTrack 7 Oct 28 17:43:12 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 17:43:14 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:17 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 28 17:43:17 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 28 17:43:17 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:21 volumio volumio[1690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 17:43:23 volumio volumio[1690]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 17:43:23 volumio volumio[1690]: Error: dns service error: unknown Oct 28 17:43:23 volumio volumio[1690]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 28 17:43:23 volumio volumio[1690]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 28 17:43:23 volumio volumio[1690]: errorCode: -65537 Oct 28 17:43:23 volumio volumio[1690]: } Oct 28 17:43:23 volumio volumio[1690]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 17:43:23 volumio sudo[1430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-28 17:42 Oct 28 17:43:23 volumio sudo[1430]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9a3927796ae9ab3a5a247866bf574288cd09ee64" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 04 Sep 2025 04:11:35 PM CEST" VOLUMIO_VERSION="3.845" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d8c10ad904a299b47c7499a1cf239286"