-- Logs begin at Sat 2024-08-31 18:15:40 JST, end at Sat 2024-08-31 22:34:50 JST. -- Aug 31 22:34:15 volumio systemd[1]: Starting Daily apt download activities... Aug 31 22:34:16 volumio systemd[1]: apt-daily.service: Succeeded. Aug 31 22:34:16 volumio systemd[1]: Started Daily apt download activities. Aug 31 22:34:16 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:16 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:17 volumio volumio[918]: info: Retrieving Cloud Streaming UI Aug 31 22:34:17 volumio volumio[918]: info: Getting Tidal Cloud Configuration Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:17 volumio volumio[918]: info: Getting Qobuz Cloud Configuration Aug 31 22:34:17 volumio volumio[918]: info: Asking plugin for UI Config Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:17 volumio volumio[918]: info: Getting Spotify Cloud Configuration Aug 31 22:34:17 volumio volumio[918]: info: Asking plugin for UI Config Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:17 volumio volumio[918]: info: Saving Spotify Acccount Aug 31 22:34:17 volumio volumio[918]: info: Got Tidal Cloud Configuration Aug 31 22:34:17 volumio volumio[918]: info: Got it Aug 31 22:34:17 volumio volumio[918]: info: Got it Aug 31 22:34:17 volumio volumio[918]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 22:34:17 volumio volumio[918]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:17 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: info: Aug 31 22:34:19 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand rescan took 4 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: info: Aug 31 22:34:19 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: info: Aug 31 22:34:19 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 8 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 7 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 5 milliseconds Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: info: Command Router : Notfying DB Updatetrue Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: info: Command Router : Notfying DB Updatetrue Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: info: Command Router : Notfying DB Updatetrue Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand status took 157 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand playlistinfo took 94 milliseconds Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand playlistinfo took 60 milliseconds Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":166839,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:19 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:19 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":166839,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:19 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:19 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: ------------------------------ 238ms Aug 31 22:34:19 volumio volumio[918]: info: ------------------------------ 237ms Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:19 volumio volumio[918]: info: sendMpdCommand playlistinfo took 87 milliseconds Aug 31 22:34:19 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:19 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":166839,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:19 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:19 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:19 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:19 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:19 volumio volumio[918]: info: ------------------------------ 279ms Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:19 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:21 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:25 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:29 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:33 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 22:34:37 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:40 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 22:34:41 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:42 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 22:34:44 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 22:34:44 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 22:34:45 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:48 volumio volumio[918]: info: MPD Database updated - AlbumList cache refreshed Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: database Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: MPD Database updated - AlbumList cache refreshed Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: database Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: MPD Database updated - AlbumList cache refreshed Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: database Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: Aug 31 22:34:48 volumio volumio[918]: ---------------------------- MPD announces state update: update Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: ControllerMpd::getState Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:34:48 volumio volumio[918]: info: sendMpdCommand status took 95 milliseconds Aug 31 22:34:48 volumio volumio[918]: info: sendMpdCommand status took 94 milliseconds Aug 31 22:34:48 volumio volumio[918]: info: sendMpdCommand status took 95 milliseconds Aug 31 22:34:48 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:49 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 22:34:49 volumio volumio[918]: info: Command Router : Notfying DB Updatefalse Aug 31 22:34:49 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:49 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:49 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:49 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:49 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1748 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1749 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1749 milliseconds Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: info: Command Router : Notfying DB Updatefalse Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1835 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1835 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand status took 1834 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 172 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 173 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: info: Command Router : Notfying DB Updatefalse Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseState Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 1966ms Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 1967ms Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 1963ms Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 1963ms Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 108 milliseconds Aug 31 22:34:50 volumio volumio[918]: info: sendMpdCommand playlistinfo took 108 milliseconds Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ControllerMpd::pushState Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::servicePushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195851,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"love the world","artist":"Perfume","album":"⊿","uri":"USB/9033-98AA/music/Perfume/⊿/02 love the world.m4a","trackType":"m4a"} Aug 31 22:34:50 volumio volumio[918]: verbose: CURRENT POSITION 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:34:50 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: CoreStateMachine::pushState Aug 31 22:34:50 volumio volumio[918]: info: CorePlayQueue::getTrack 1 Aug 31 22:34:50 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 2027ms Aug 31 22:34:50 volumio volumio[918]: info: ------------------------------ 2028ms Aug 31 22:34:50 volumio volumio[918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:34:50 volumio volumio[918]: Error: dns service error: unknown Aug 31 22:34:50 volumio volumio[918]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 31 22:34:50 volumio volumio[918]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 31 22:34:50 volumio volumio[918]: errorCode: -65537 Aug 31 22:34:50 volumio volumio[918]: } Aug 31 22:34:50 volumio volumio[918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:34:50 volumio sudo[7998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 22:33 Aug 31 22:34:50 volumio sudo[7998]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"