-- Logs begin at Tue 2024-10-22 15:47:25 CST, end at Tue 2024-10-22 15:57:40 CST. -- Oct 22 15:56:10 volumio-603 volumio[1079]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 22 15:56:10 volumio-603 volumio[1079]: info: Retrying Login Due to Network Error Oct 22 15:56:20 volumio-603 volumio[1079]: info: MyVolumio login type: Token Oct 22 15:56:27 volumio-603 volumiologrotate[695]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 22 15:56:27 volumio-603 volumiologrotate[695]: ls: cannot access '603': No such file or directory Oct 22 15:56:48 volumio-603 kernel: perf: interrupt took too long (3245 > 3243), lowering kernel.perf_event_max_sample_rate to 61500 Oct 22 15:56:50 volumio-603 volumio[1079]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 22 15:56:50 volumio-603 volumio[1079]: info: Retrying Login Due to Network Error Oct 22 15:56:57 volumio-603 ntpd[1081]: 193.182.111.142 local addr 192.168.1.11 -> Oct 22 15:57:00 volumio-603 volumio[1079]: info: MyVolumio login type: Token Oct 22 15:57:18 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioGetState Oct 22 15:57:18 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: info: Retrieving Cloud Streaming UI Oct 22 15:57:20 volumio-603 volumio[1079]: info: Getting Tidal Cloud Configuration Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: info: Getting Qobuz Cloud Configuration Oct 22 15:57:20 volumio-603 volumio[1079]: info: Asking plugin for UI Config Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: info: Getting Spotify Cloud Configuration Oct 22 15:57:20 volumio-603 volumio[1079]: info: Asking plugin for UI Config Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: info: Saving Spotify Acccount Oct 22 15:57:20 volumio-603 volumio[1079]: info: Got Tidal Cloud Configuration Oct 22 15:57:20 volumio-603 volumio[1079]: info: Got it Oct 22 15:57:20 volumio-603 volumio[1079]: info: Got it Oct 22 15:57:20 volumio-603 volumio[1079]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 15:57:20 volumio-603 volumio[1079]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 15:57:20 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 15:57:21 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand update Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:21 volumio-603 volumio[1079]: info: Oct 22 15:57:21 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:21 volumio-603 volumio[1079]: info: sendMpdCommand update took 8 milliseconds Oct 22 15:57:21 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:21 volumio-603 volumio[1079]: info: Oct 22 15:57:21 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:21 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:21 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:21 volumio-603 volumio[1079]: info: Oct 22 15:57:21 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:22 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:22 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 19 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 14 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 13 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 12 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 10 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: info: sendMpdCommand status took 8 milliseconds Oct 22 15:57:22 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:24 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: No code Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: No code Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: No code Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:24 volumio-603 volumio[1079]: info: ------------------------------ 2885ms Oct 22 15:57:24 volumio-603 volumio[1079]: info: ------------------------------ 2879ms Oct 22 15:57:24 volumio-603 volumio[1079]: info: ------------------------------ 2876ms Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand rescan Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 22 15:57:24 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand update Oct 22 15:57:24 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 15:57:24 volumio-603 volumio[1079]: info: sendMpdCommand rescan took 43 milliseconds Oct 22 15:57:24 volumio-603 volumio[1079]: info: sendMpdCommand update took 42 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: Oct 22 15:57:25 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 75 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 71 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 70 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 65 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 62 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: info: sendMpdCommand status took 61 milliseconds Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: No code Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: No code Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: No code Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:25 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: No code Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:25 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:25 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 913ms Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 912ms Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 904ms Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 906ms Oct 22 15:57:26 volumio-603 volumio[1079]: info: sendMpdCommand status took 941 milliseconds Oct 22 15:57:26 volumio-603 volumio[1079]: info: sendMpdCommand status took 939 milliseconds Oct 22 15:57:26 volumio-603 volumio[1079]: info: sendMpdCommand status took 938 milliseconds Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:26 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:26 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: No code Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:26 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: No code Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:26 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 1348ms Oct 22 15:57:26 volumio-603 volumio[1079]: info: ------------------------------ 1349ms Oct 22 15:57:27 volumio-603 volumiologrotate[695]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 22 15:57:27 volumio-603 volumiologrotate[695]: ls: cannot access '603': No such file or directory Oct 22 15:57:27 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioGetState Oct 22 15:57:27 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: MPD Database updated - AlbumList cache refreshed Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: database Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 69 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 65 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 63 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 323ms Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 324ms Oct 22 15:57:30 volumio-603 volumio[1079]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 22 15:57:30 volumio-603 volumio[1079]: info: Retrying Login Due to Network Error Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 342 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 339 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 337 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 330 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 326 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 324 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatetrue Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: No code Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 707ms Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 706ms Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 696ms Oct 22 15:57:30 volumio-603 volumio[1079]: info: ------------------------------ 696ms Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: Oct 22 15:57:30 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: update Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 428 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 422 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 57 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 55 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 53 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: info: sendMpdCommand status took 52 milliseconds Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatefalse Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatefalse Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: Command Router : Notfying DB Updatefalse Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::Close All Modals sent Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:30 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:30 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:30 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:30 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: No code Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:31 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: No code Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 15:57:31 volumio-603 volumio[1079]: verbose: CURRENT POSITION 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService stop Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: No code Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 0 Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:31 volumio-603 volumio[1079]: info: ------------------------------ 783ms Oct 22 15:57:31 volumio-603 volumio[1079]: info: ------------------------------ 412ms Oct 22 15:57:31 volumio-603 volumio[1079]: info: ------------------------------ 409ms Oct 22 15:57:31 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 15:57:31 volumio-603 volumio[1079]: info: CURURI: music-library Oct 22 15:57:31 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:32 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 15:57:32 volumio-603 volumio[1079]: info: CURURI: music-library/NAS Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:32 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 15:57:32 volumio-603 volumio[1079]: info: CURURI: music-library/NAS/dsd4 Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/01 - Various - War Drums in Red Cliff.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/08 - 渡口.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/12 - Unknown Artist.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/12 - Wang Chieh - Forgetting You Forgetting Me.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/15 - Cry.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/09 - 天荒愛未老 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/10 - 世界不可怕 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/11 - 投其所好 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/dsd4/12 - 一瞬間(國) (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/01 - Various - War Drums in Red Cliff.dff in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F01%20-%20Various%20-%20War%20Drums%20in%20Red%20Cliff.dff&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/01 - Various - War Drums in Red Cliff.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/08 - 渡口.dff in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F08%20-%20%E6%B8%A1%E5%8F%A3.dff&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/08 - 渡口.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/12 - Unknown Artist.dff in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F12%20-%20Unknown%20Artist.dff&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/12 - Unknown Artist.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/12 - Wang Chieh - Forgetting You Forgetting Me.dff in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F12%20-%20Wang%20Chieh%20-%20Forgetting%20You%20Forgetting%20Me.dff&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/12 - Wang Chieh - Forgetting You Forgetting Me.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/15 - Cry.dff in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F15%20-%20Cry.dff&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/15 - Cry.dff Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/09 - 天荒愛未老 (DSD64).dsf in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E9%99%B3%E6%BD%94%E9%BA%97%20Lily%20Chan/Purely%20(DSD)/extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F09%20-%20%E5%A4%A9%E8%8D%92%E6%84%9B%E6%9C%AA%E8%80%81%20(DSD64).dsf&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/09 - 天荒愛未老 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/10 - 世界不可怕 (DSD64).dsf in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E9%99%B3%E6%BD%94%E9%BA%97%20Lily%20Chan/Purely%20(DSD)/extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F10%20-%20%E4%B8%96%E7%95%8C%E4%B8%8D%E5%8F%AF%E6%80%95%20(DSD64).dsf&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/10 - 世界不可怕 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/11 - 投其所好 (DSD64).dsf in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E9%99%B3%E6%BD%94%E9%BA%97%20Lily%20Chan/Purely%20(DSD)/extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F11%20-%20%E6%8A%95%E5%85%B6%E6%89%80%E5%A5%BD%20(DSD64).dsf&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/11 - 投其所好 (DSD64).dsf Oct 22 15:57:32 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/dsd4/12 - 一瞬間(國) (DSD64).dsf in service mpd Oct 22 15:57:32 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E9%99%B3%E6%BD%94%E9%BA%97%20Lily%20Chan/Purely%20(DSD)/extralarge&path=%2Fmnt%2FNAS%2Fdsd4%2F12%20-%20%E4%B8%80%E7%9E%AC%E9%96%93(%E5%9C%8B)%20(DSD64).dsf&metadata=false Oct 22 15:57:32 volumio-603 volumio[1079]: info: URI /mnt/NAS/dsd4/12 - 一瞬間(國) (DSD64).dsf Oct 22 15:57:34 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 15:57:34 volumio-603 volumio[1079]: info: CURURI: music-library/NAS/五音 Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/五音/林淑容 - 昨夜星辰.wav Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/五音/老猫 - 你是我的妞.ape Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/五音/周杰伦 - 我是如此相信 (电影《天火》主题曲)[24-48].flac Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/五音/云朵 - 我的楼兰-1.flac Oct 22 15:57:34 volumio-603 volumio[1079]: info: Preloading song: music-library/NAS/五音/云朵 - 我的楼兰.flac Oct 22 15:57:34 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/五音/林淑容 - 昨夜星辰.wav in service mpd Oct 22 15:57:34 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2F%E4%BA%94%E9%9F%B3%2F%E6%9E%97%E6%B7%91%E5%AE%B9%20-%20%E6%98%A8%E5%A4%9C%E6%98%9F%E8%BE%B0.wav&metadata=false Oct 22 15:57:34 volumio-603 volumio[1079]: info: URI /mnt/NAS/五音/林淑容 - 昨夜星辰.wav Oct 22 15:57:34 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/五音/老猫 - 你是我的妞.ape in service mpd Oct 22 15:57:34 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%5B51ape.com%5D%E8%80%81%E7%8C%AB//extralarge&path=%2Fmnt%2FNAS%2F%E4%BA%94%E9%9F%B3%2F%E8%80%81%E7%8C%AB%20-%20%E4%BD%A0%E6%98%AF%E6%88%91%E7%9A%84%E5%A6%9E.ape&metadata=false Oct 22 15:57:34 volumio-603 volumio[1079]: info: URI /mnt/NAS/五音/老猫 - 你是我的妞.ape Oct 22 15:57:34 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/五音/周杰伦 - 我是如此相信 (电影《天火》主题曲)[24-48].flac in service mpd Oct 22 15:57:34 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E5%91%A8%E6%9D%B0%E4%BC%A6/%E6%88%91%E6%98%AF%E5%A6%82%E6%AD%A4%E7%9B%B8%E4%BF%A1/extralarge&path=%2Fmnt%2FNAS%2F%E4%BA%94%E9%9F%B3%2F%E5%91%A8%E6%9D%B0%E4%BC%A6%20-%20%E6%88%91%E6%98%AF%E5%A6%82%E6%AD%A4%E7%9B%B8%E4%BF%A1%C2%A0(%E7%94%B5%E5%BD%B1%E3%80%8A%E5%A4%A9%E7%81%AB%E3%80%8B%E4%B8%BB%E9%A2%98%E6%9B%B2)%5B24-48%5D.flac&metadata=false Oct 22 15:57:34 volumio-603 volumio[1079]: info: URI /mnt/NAS/五音/周杰伦 - 我是如此相信 (电影《天火》主题曲)[24-48].flac Oct 22 15:57:34 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/五音/云朵 - 我的楼兰-1.flac in service mpd Oct 22 15:57:34 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E4%BA%91%E6%9C%B5/%E7%BC%98%E5%AE%9A%E4%BB%8A%E7%94%9F/extralarge&path=%2Fmnt%2FNAS%2F%E4%BA%94%E9%9F%B3%2F%E4%BA%91%E6%9C%B5%20-%20%E6%88%91%E7%9A%84%E6%A5%BC%E5%85%B0-1.flac&metadata=false Oct 22 15:57:34 volumio-603 volumio[1079]: info: URI /mnt/NAS/五音/云朵 - 我的楼兰-1.flac Oct 22 15:57:34 volumio-603 volumio[1079]: info: Exploding uri music-library/NAS/五音/云朵 - 我的楼兰.flac in service mpd Oct 22 15:57:34 volumio-603 volumio[1079]: info: ALBUMART /albumart?cacheid=862&web=%E4%BA%91%E6%9C%B5/%E7%BC%98%E5%AE%9A%E4%BB%8A%E7%94%9F/extralarge&path=%2Fmnt%2FNAS%2F%E4%BA%94%E9%9F%B3%2F%E4%BA%91%E6%9C%B5%20-%20%E6%88%91%E7%9A%84%E6%A5%BC%E5%85%B0.flac&metadata=false Oct 22 15:57:34 volumio-603 volumio[1079]: info: URI /mnt/NAS/五音/云朵 - 我的楼兰.flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::ClearQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::stop Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::clearPlayQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::saveQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::addQueueItems Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::addQueueItems Oct 22 15:57:38 volumio-603 volumio[1079]: info: Preload queue cleared Oct 22 15:57:38 volumio-603 volumio[1079]: info: Adding Item to queue: music-library/NAS/五音/林淑容 - 昨夜星辰.wav Oct 22 15:57:38 volumio-603 volumio[1079]: info: Using cached record of: music-library/NAS/五音/林淑容 - 昨夜星辰.wav Oct 22 15:57:38 volumio-603 volumio[1079]: info: Adding Item to queue: music-library/NAS/五音/老猫 - 你是我的妞.ape Oct 22 15:57:38 volumio-603 volumio[1079]: info: Using cached record of: music-library/NAS/五音/老猫 - 你是我的妞.ape Oct 22 15:57:38 volumio-603 volumio[1079]: info: Adding Item to queue: music-library/NAS/五音/周杰伦 - 我是如此相信 (电影《天火》主题曲)[24-48].flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Using cached record of: music-library/NAS/五音/周杰伦 - 我是如此相信 (电影《天火》主题曲)[24-48].flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Adding Item to queue: music-library/NAS/五音/云朵 - 我的楼兰-1.flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Using cached record of: music-library/NAS/五音/云朵 - 我的楼兰-1.flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Adding Item to queue: music-library/NAS/五音/云朵 - 我的楼兰.flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: Using cached record of: music-library/NAS/五音/云朵 - 我的楼兰.flac Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::saveQueue Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::updateTrackBlock Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrackBlock Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPlay Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::play index 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::stop Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::play index undefined Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::startPlaybackTimer Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::clearAddPlayTracks NAS/五音/云朵 - 我的楼兰-1.flac Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand stop took 11 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand clear took 2 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand add "NAS/五音/云朵 - 我的楼兰-1.flac" Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand add "NAS/五音/云朵 - 我的楼兰-1.flac" took 18 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 15ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 11ms Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand play Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces system playlist update Oct 22 15:57:38 volumio-603 volumio[1079]: info: Ignoring MPD Status Update Oct 22 15:57:38 volumio-603 volumio[1079]: error: Upnp client error: Error: This socket has been ended by the other party Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 32ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 30ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand play took 24 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 16ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 14ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 12 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 8 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 6 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: Oct 22 15:57:38 volumio-603 volumio[1079]: ---------------------------- MPD announces state update: player Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::getState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 15 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 9 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus play Oct 22 15:57:38 volumio-603 volumio[1079]: info: Received an update from plugin. extracting info from payload Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus play Oct 22 15:57:38 volumio-603 volumio[1079]: info: Received an update from plugin. extracting info from payload Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 85ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 148ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: ------------------------------ 147ms Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand status took 132 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 126 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 121 milliseconds Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseState Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus play Oct 22 15:57:38 volumio-603 volumio[1079]: info: Received an update from plugin. extracting info from payload Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:38 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus play Oct 22 15:57:38 volumio-603 volumio[1079]: info: Received an update from plugin. extracting info from payload Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:38 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:38 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: ------------------------------ 311ms Oct 22 15:57:39 volumio-603 volumio[1079]: info: ------------------------------ 302ms Oct 22 15:57:39 volumio-603 volumio[1079]: info: sendMpdCommand playlistinfo took 201 milliseconds Oct 22 15:57:39 volumio-603 volumio[1079]: verbose: ControllerMpd::parseTrackInfo Oct 22 15:57:39 volumio-603 volumio[1079]: info: ControllerMpd::pushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreCommandRouter::servicePushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:39 volumio-603 volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4299 Kbps","isStreaming":false,"title":"我的楼兰","artist":"云朵","album":"缘定今生","uri":"NAS/五音/云朵 - 我的楼兰-1.flac","trackType":"flac"} Oct 22 15:57:39 volumio-603 volumio[1079]: verbose: CURRENT POSITION 3 Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreStateMachine::syncState stateService play Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreStateMachine::syncState currentStatus play Oct 22 15:57:39 volumio-603 volumio[1079]: info: Received an update from plugin. extracting info from payload Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreStateMachine::pushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: CorePlayQueue::getTrack 3 Oct 22 15:57:39 volumio-603 volumio[1079]: info: CoreCommandRouter::volumioPushState Oct 22 15:57:39 volumio-603 volumio[1079]: info: ------------------------------ 412ms Oct 22 15:57:39 volumio-603 volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 15:57:39 volumio-603 volumio[1079]: Error: connect ECONNREFUSED 31.13.106.4:443 Oct 22 15:57:39 volumio-603 volumio[1079]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 22 15:57:39 volumio-603 volumio[1079]: errno: -111, Oct 22 15:57:39 volumio-603 volumio[1079]: code: 'ECONNREFUSED', Oct 22 15:57:39 volumio-603 volumio[1079]: syscall: 'connect', Oct 22 15:57:39 volumio-603 volumio[1079]: address: '31.13.106.4', Oct 22 15:57:39 volumio-603 volumio[1079]: port: 443 Oct 22 15:57:39 volumio-603 volumio[1079]: } Oct 22 15:57:39 volumio-603 volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 15:57:40 volumio-603 kernel: sched: RT throttling activated Oct 22 15:57:40 volumio-603 sudo[2558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 15:56 Oct 22 15:57:40 volumio-603 sudo[2558]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"