Dec 07 09:57:00 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:00 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: info: Retrieving Cloud Streaming UI Dec 07 09:57:06 volumio volumio[1135]: info: Getting Tidal Cloud Configuration Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: info: Getting Qobuz Cloud Configuration Dec 07 09:57:06 volumio volumio[1135]: info: Asking plugin for UI Config Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: info: Getting Spotify Cloud Configuration Dec 07 09:57:06 volumio volumio[1135]: info: Asking plugin for UI Config Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: info: Saving Spotify Acccount Dec 07 09:57:06 volumio volumio[1135]: info: Got Tidal Cloud Configuration Dec 07 09:57:06 volumio volumio[1135]: info: Got it Dec 07 09:57:06 volumio volumio[1135]: info: Got it Dec 07 09:57:06 volumio volumio[1135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:06 volumio volumio[1135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 09:57:07 volumio volumio[1135]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 07 09:57:07 volumio volumio[1135]: info: Received Get System Version Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 09:57:07 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:07 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:07 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand update Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: info: Dec 07 09:57:10 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand update took 8 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 3 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 1 milliseconds Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: info: Command Router : Notfying DB Updatetrue Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: info: Dec 07 09:57:10 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: info: Dec 07 09:57:10 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 64 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 16 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 15 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand status took 15 milliseconds Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: info: Command Router : Notfying DB Updatetrue Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: info: Command Router : Notfying DB Updatetrue Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18959,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:10 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:10 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: ------------------------------ 466ms Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 240 milliseconds Dec 07 09:57:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 147 milliseconds Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19074,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:10 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:10 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19074,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:10 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:10 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:10 volumio volumio[1135]: info: ------------------------------ 557ms Dec 07 09:57:10 volumio volumio[1135]: info: ------------------------------ 545ms Dec 07 09:57:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:11 volumio volumio[1135]: info: MPD Database updated - AlbumList cache refreshed Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: database Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: MPD Database updated - AlbumList cache refreshed Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: database Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: MPD Database updated - AlbumList cache refreshed Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: database Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: Dec 07 09:57:11 volumio volumio[1135]: ---------------------------- MPD announces state update: update Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: ControllerMpd::getState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 197 milliseconds Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 173 milliseconds Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 172 milliseconds Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:11 volumio volumio[1135]: info: Command Router : Notfying DB Updatefalse Dec 07 09:57:11 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 731 milliseconds Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 748 milliseconds Dec 07 09:57:11 volumio volumio[1135]: info: sendMpdCommand status took 755 milliseconds Dec 07 09:57:11 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:11 volumio volumio[1135]: info: Command Router : Notfying DB Updatefalse Dec 07 09:57:11 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand status took 1057 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand status took 1069 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand status took 1069 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 683 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 683 milliseconds Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:12 volumio volumio[1135]: info: Command Router : Notfying DB Updatefalse Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1266ms Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1282ms Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 419 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 418 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 07 09:57:12 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 102 milliseconds Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ControllerMpd::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20059,"duration":3564,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/NAS/惠威/CDImage.ape","trackType":"ape"} Dec 07 09:57:12 volumio volumio[1135]: verbose: CURRENT POSITION 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Dec 07 09:57:12 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 07 09:57:12 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:12 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1456ms Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1508ms Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1515ms Dec 07 09:57:12 volumio volumio[1135]: info: ------------------------------ 1516ms Dec 07 09:57:14 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:22 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:30 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:30 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:57:32 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:32 volumio sudo[1966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 09:57:32 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:32 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:32 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:32 volumio volumio[1135]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.225 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:32 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 09:57:32 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:32 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:32 volumio volumio[1135]: info: Listing playlists Dec 07 09:57:32 volumio volumio[1135]: info: CoreCommandRouter::volumioGetQueue Dec 07 09:57:32 volumio volumio[1135]: info: CoreStateMachine::getQueue Dec 07 09:57:32 volumio volumio[1135]: info: CorePlayQueue::getQueue Dec 07 09:57:33 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 07 09:57:42 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 09:57:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 07 09:57:50 volumio volumio[1135]: info: MyVolumio login type: Token Dec 07 09:57:51 volumio volumio[1135]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 07 09:57:52 volumio volumio[1135]: info: MyVolumio token set successfully Dec 07 09:57:52 volumio volumio[1135]: info: MYVOLUMIO: Adding device Dec 07 09:57:52 volumio volumio[1135]: info: MYVOLUMIO: Evaluating Server Dec 07 09:57:53 volumio volumio[1135]: info: MyVolumio status changed Dec 07 09:57:53 volumio volumio[1135]: info: Streaming services startup Dec 07 09:57:53 volumio volumio[1135]: info: Starting Streaming Daemon Dec 07 09:57:53 volumio volumio[1135]: info: Removing browser output: myVolumio user plan is not superstar Dec 07 09:57:53 volumio volumio[1135]: info: Removing audio output: Dec 07 09:57:53 volumio volumio[1135]: info: Stoppping Tunnel 1 Dec 07 09:57:53 volumio sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 09:57:53 volumio sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:53 volumio sudo[2063]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:53 volumio volumio[1135]: error: Cannot start Volumio Streaming Daemon Dec 07 09:57:53 volumio volumio[1135]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 07 09:57:53 volumio volumio[1135]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 07 09:57:53 volumio sudo[2066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 07 09:57:53 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio sudo[2071]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:57:53 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 09:57:53 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 09:57:53 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:53 volumio volumio[1135]: info: Remote SSH Stopped Dec 07 09:57:53 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:57:53 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:53 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Dec 07 09:57:53 volumio volumio[1135]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.225 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:53 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 09:57:53 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:53 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: Listing playlists Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetQueue Dec 07 09:57:53 volumio volumio[1135]: info: CoreStateMachine::getQueue Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getQueue Dec 07 09:57:53 volumio volumio[1135]: info: Received Get System Info Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 09:57:53 volumio volumio[1135]: info: Discovery: Getting this device information Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 09:57:53 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 07 09:57:54 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:57:54 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:57:55 volumio volumio[1135]: info: Setting Geolocation for MyVolumio to eu2 Dec 07 09:57:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:55 volumio volumio[1135]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 07 09:57:56 volumio volumio[1135]: info: Updating MyVolumio device info Dec 07 09:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:57:56 volumio volumio[1135]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 07 09:57:58 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:58 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:59 volumio volumio[1135]: info: Retrieving Cloud Streaming UI Dec 07 09:57:59 volumio volumio[1135]: info: Getting Tidal Cloud Configuration Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:59 volumio volumio[1135]: info: Getting Qobuz Cloud Configuration Dec 07 09:57:59 volumio volumio[1135]: info: Asking plugin for UI Config Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:59 volumio volumio[1135]: info: Getting Spotify Cloud Configuration Dec 07 09:57:59 volumio volumio[1135]: info: Asking plugin for UI Config Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:59 volumio volumio[1135]: info: Saving Spotify Acccount Dec 07 09:57:59 volumio volumio[1135]: info: Got Tidal Cloud Configuration Dec 07 09:57:59 volumio volumio[1135]: info: Got it Dec 07 09:57:59 volumio volumio[1135]: info: Got it Dec 07 09:57:59 volumio volumio[1135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:57:59 volumio volumio[1135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 09:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 07 09:57:59 volumio volumio[1135]: info: MYVOLUMIO: Adding device Dec 07 09:57:59 volumio volumio[1135]: info: MYVOLUMIO: Evaluating Server Dec 07 09:58:02 volumio volumio[1135]: info: Setting Geolocation for MyVolumio to eu2 Dec 07 09:58:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:02 volumio volumio[1135]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 07 09:58:03 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 07 09:58:03 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 07 09:58:03 volumio volumio[1135]: info: Updating MyVolumio device info Dec 07 09:58:03 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:03 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:03 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 09:58:03 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 09:58:03 volumio volumio[1135]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 07 09:58:06 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 09:58:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 07 09:58:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 07 09:58:06 volumio sudo[2147]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 09:58:06 volumio sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 07 09:58:06 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 09:58:06 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio sudo[2147]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 07 09:58:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 07 09:58:06 volumio sudo[2167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:06 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 09:58:06 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2166]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 09:58:06 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:06 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:06 volumio sudo[2172]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 07 09:58:06 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:07 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 07 09:58:30 volumio volumio[1135]: info: Saving new wireless network Dec 07 09:58:30 volumio sudo[2271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 07 09:58:30 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:30 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 07 09:58:30 volumio volumio[1135]: info: Discovery: Restarting Advertising due to device name change Dec 07 09:58:30 volumio sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 07 09:58:30 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:30 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 07 09:58:30 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 07 09:58:30 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 07 09:58:30 volumio systemd[1]: wireless.service: Consumed 8min 10.324s CPU time. Dec 07 09:58:31 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 07 09:58:31 volumio volumio[1135]: info: Discovery: A device disappeared from network Dec 07 09:58:31 volumio volumio[1135]: info: Discovery: Device volumio disappeared from network Dec 07 09:58:31 volumio volumio[1135]: info: Discovery: A device disappeared from network Dec 07 09:58:31 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:31 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:31 volumio wireless.js[2281]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 07 09:58:31 volumio wireless.js[2281]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 07 09:58:31 volumio wireless.js[2281]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 07 09:58:31 volumio wireless.js[2281]: WIRELESS.JS: Cleaning previous... Dec 07 09:58:31 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:32 volumio sudo[2304]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 07 09:58:32 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 09:58:32 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:32 volumio sudo[2306]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 07 09:58:32 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 09:58:32 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:32 volumio wireless.js[2281]: WIRELESS.JS: Stopped aP Dec 07 09:58:32 volumio sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:32 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:32 volumio sudo[2318]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 07 09:58:32 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:32 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 07 09:58:32 volumio sudo[2318]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:32 volumio sudo[2321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 07 09:58:32 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:32 volumio sudo[2321]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:33 volumio sudo[2329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 07 09:58:33 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:33 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:33 volumio sudo[2331]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 07 09:58:33 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:33 volumio sudo[2336]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:33 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:33 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:34 volumio sudo[2331]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:34 volumio wireless.js[2281]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: CN Dec 07 09:58:34 volumio sudo[2342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 07 09:58:34 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:34 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:34 volumio sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Dec 07 09:58:34 volumio sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:34 volumio sudo[2344]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:34 volumio wireless.js[2281]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: CN Dec 07 09:58:34 volumio wireless.js[2281]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 07 09:58:34 volumio wireless.js[2281]: WIRELESS.JS: Notified systemd about wireless ready Dec 07 09:58:34 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 07 09:58:34 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 07 09:58:34 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:34 volumio sudo[2352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:34 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:34 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:35 volumio volumio[1135]: info: Discovery: Started advertising with name: Volumio Dec 07 09:58:35 volumio sudo[2358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 09:58:35 volumio sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 09:58:35 volumio sudo[2358]: pam_unix(sudo:session): session closed for user root Dec 07 09:58:36 volumio ntpd[978]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 07 09:58:36 volumio ntpd[978]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 07 09:58:36 volumio ntpd[978]: DNS: Pool skipping: 198.18.0.69 Dec 07 09:58:36 volumio ntpd[978]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 07 09:58:36 volumio volumio[1135]: compat.c: read() failed: Resource temporarily unavailable Dec 07 09:58:36 volumio volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 09:58:36 volumio volumio[1135]: Error: dns service error: unknown Dec 07 09:58:36 volumio volumio[1135]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 07 09:58:36 volumio volumio[1135]: errorCode: -65537 Dec 07 09:58:36 volumio volumio[1135]: } Dec 07 09:58:36 volumio volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 09:58:37 volumio ntpd[978]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 07 09:58:37 volumio ntpd[978]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 07 09:58:37 volumio ntpd[978]: DNS: Pool skipping: 198.18.0.70 Dec 07 09:58:37 volumio ntpd[978]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 07 09:58:38 volumio ntpd[978]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 07 09:58:38 volumio ntpd[978]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 07 09:58:38 volumio ntpd[978]: DNS: Pool skipping: 198.18.0.71 Dec 07 09:58:38 volumio ntpd[978]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 07 09:58:39 volumio ntpd[978]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 07 09:58:39 volumio ntpd[978]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 07 09:58:39 volumio ntpd[978]: DNS: Pool skipping: 198.18.8.22 Dec 07 09:58:39 volumio ntpd[978]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 07 09:58:40 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 09:57' Dec 07 09:58:40 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"