-- Logs begin at Mon 2025-12-22 21:10:22 JST, end at Sat 2025-12-27 19:37:53 JST. -- Dec 27 19:36:22 volumio go-librespot[29027]: time="2025-12-27T19:36:22+09:00" level=trace msg="sent dealer ping" Dec 27 19:36:22 volumio go-librespot[29027]: time="2025-12-27T19:36:22+09:00" level=trace msg="received dealer pong" Dec 27 19:36:38 volumio sudo[28295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 19:36:38 volumio sudo[28295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 19:36:38 volumio sudo[28295]: pam_unix(sudo:session): session closed for user root Dec 27 19:36:38 volumio sudo[28298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 19:36:38 volumio sudo[28298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 19:36:38 volumio sudo[28298]: pam_unix(sudo:session): session closed for user root Dec 27 19:36:38 volumio volumio[1040]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:38 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::volumioGetQueue Dec 27 19:36:38 volumio volumio[1040]: info: CoreStateMachine::getQueue Dec 27 19:36:38 volumio volumio[1040]: info: CorePlayQueue::getQueue Dec 27 19:36:38 volumio volumio[1040]: info: Listing playlists Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 19:36:38 volumio volumio[1040]: info: Received Get System Info Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 19:36:38 volumio volumio[1040]: info: Discovery: Getting this device information Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:38 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:38 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 19:36:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 19:36:40 volumio volumio[1040]: info: Received Get System Info Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 19:36:40 volumio volumio[1040]: info: Discovery: Getting this device information Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:40 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:40 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 19:36:41 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 19:36:41 volumio volumio[1040]: info: Received Get System Info Dec 27 19:36:41 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 19:36:41 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 19:36:41 volumio volumio[1040]: info: Discovery: Getting this device information Dec 27 19:36:41 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:41 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:41 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 19:36:46 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:46 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:48 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 19:36:52 volumio go-librespot[29027]: time="2025-12-27T19:36:52+09:00" level=trace msg="sent dealer ping" Dec 27 19:36:52 volumio go-librespot[29027]: time="2025-12-27T19:36:52+09:00" level=trace msg="received dealer pong" Dec 27 19:36:56 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:56 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 19:36:57 volumio volumio[1040]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 19:36:57 volumio volumio[1040]: info: Received Get System Version Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 19:36:57 volumio volumio[1040]: info: Received Get System Info Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 19:36:57 volumio volumio[1040]: info: Discovery: Getting this device information Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:36:57 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:36:57 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 27 19:36:59 volumio volumio[1040]: Disk list : [] Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 27 19:36:59 volumio volumio[1040]: info: Received Get System Version Dec 27 19:36:59 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 19:37:20 volumio go-librespot[29027]: time="2025-12-27T19:37:20+09:00" level=trace msg="received accesspoint ping" Dec 27 19:37:20 volumio go-librespot[29027]: time="2025-12-27T19:37:20+09:00" level=trace msg="received accesspoint pong ack" Dec 27 19:37:22 volumio go-librespot[29027]: time="2025-12-27T19:37:22+09:00" level=trace msg="sent dealer ping" Dec 27 19:37:22 volumio go-librespot[29027]: time="2025-12-27T19:37:22+09:00" level=trace msg="received dealer pong" Dec 27 19:37:25 volumio volumio[1040]: info: CoreCommandRouter::volumioGetState Dec 27 19:37:25 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: info: Retrieving Cloud Streaming UI Dec 27 19:37:30 volumio volumio[1040]: info: Getting Tidal Cloud Configuration Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: info: Getting Qobuz Cloud Configuration Dec 27 19:37:30 volumio volumio[1040]: info: Asking plugin for UI Config Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: info: Getting Spotify Cloud Configuration Dec 27 19:37:30 volumio volumio[1040]: info: Asking plugin for UI Config Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: info: Saving Spotify Acccount Dec 27 19:37:30 volumio volumio[1040]: info: Got Tidal Cloud Configuration Dec 27 19:37:30 volumio volumio[1040]: info: Got it Dec 27 19:37:30 volumio volumio[1040]: info: Got it Dec 27 19:37:30 volumio volumio[1040]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 19:37:30 volumio volumio[1040]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::volumioGetBrowseSources Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::volumioGetBrowseSources Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::volumioGetBrowseSources Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:30 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 27 19:37:34 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:35 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::sendMpdCommand rescan Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::sendMpdCommand status Dec 27 19:37:35 volumio volumio[1040]: info: Dec 27 19:37:35 volumio volumio[1040]: ---------------------------- MPD announces state update: update Dec 27 19:37:35 volumio volumio[1040]: info: sendMpdCommand rescan took 4 milliseconds Dec 27 19:37:35 volumio volumio[1040]: info: ControllerMpd::getState Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::sendMpdCommand status Dec 27 19:37:35 volumio volumio[1040]: info: sendMpdCommand status took 4 milliseconds Dec 27 19:37:35 volumio volumio[1040]: info: sendMpdCommand status took 1 milliseconds Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::parseState Dec 27 19:37:35 volumio volumio[1040]: info: Command Router : Notfying DB Updatetrue Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::parseState Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 19:37:35 volumio volumio[1040]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 19:37:35 volumio volumio[1040]: verbose: ControllerMpd::parseTrackInfo Dec 27 19:37:35 volumio volumio[1040]: info: ControllerMpd::pushState Dec 27 19:37:35 volumio volumio[1040]: info: CoreCommandRouter::servicePushState Dec 27 19:37:35 volumio volumio[1040]: info: CoreStateMachine::pushState Dec 27 19:37:35 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:37:35 volumio volumio[1040]: info: CoreCommandRouter::volumioPushState Dec 27 19:37:35 volumio volumio[1040]: info: CorePlayQueue::getTrack 5 Dec 27 19:37:35 volumio volumio[1040]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Dec 27 19:37:35 volumio volumio[1040]: info: ------------------------------ 57ms Dec 27 19:37:35 volumio volumio[1040]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 27 19:37:38 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:40 volumio volumio[1040]: info: CoreCommandRouter::Close All Modals sent Dec 27 19:37:42 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:46 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:47 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 27 19:37:47 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 27 19:37:50 volumio volumio[1040]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 19:37:52 volumio go-librespot[29027]: time="2025-12-27T19:37:52+09:00" level=trace msg="sent dealer ping" Dec 27 19:37:52 volumio go-librespot[29027]: time="2025-12-27T19:37:52+09:00" level=trace msg="received dealer pong" Dec 27 19:37:53 volumio volumio[1040]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 19:37:53 volumio volumio[1040]: Error: dns service error: unknown Dec 27 19:37:53 volumio volumio[1040]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Dec 27 19:37:53 volumio volumio[1040]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 27 19:37:53 volumio volumio[1040]: errorCode: -65537 Dec 27 19:37:53 volumio volumio[1040]: } Dec 27 19:37:53 volumio volumio[1040]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 19:37:53 volumio sudo[28506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 19:36 Dec 27 19:37:53 volumio sudo[28506]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"