-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Wed 2024-08-21 09:31:57 +07. -- Aug 21 09:30:00 volumio volumio[953]: info: Updating MyVolumio device info Aug 21 09:30:00 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 09:30:00 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 09:30:00 volumio volumio[953]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Aug 21 09:30:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 21 09:30:05 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:05 volumio volumio[953]: info: CoreCommandRouter::volumioPlay Aug 21 09:30:05 volumio volumio[953]: info: CoreStateMachine::play index undefined Aug 21 09:30:05 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 09:30:05 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:05 volumio volumio[953]: info: CoreStateMachine::startPlaybackTimer Aug 21 09:30:05 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:05 volumio volumio[953]: info: [1724207405983] ControllerUPNPBrowser::clearAddPlayTrack Aug 21 09:30:05 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand stop Aug 21 09:30:05 volumio volumio[953]: info: sendMpdCommand stop took 2 milliseconds Aug 21 09:30:05 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clear Aug 21 09:30:05 volumio volumio[953]: info: sendMpdCommand clear took 1 milliseconds Aug 21 09:30:05 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" Aug 21 09:30:05 volumio volumio[953]: info: Aug 21 09:30:05 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:05 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:05 volumio volumio[953]: info: Aug 21 09:30:05 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:05 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:05 volumio volumio[953]: info: Aug 21 09:30:05 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:05 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:05 volumio volumio[953]: error: Upnp client error: Error: This socket has been ended by the other party Aug 21 09:30:09 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" Aug 21 09:30:09 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:09 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:09 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:09 volumio volumio[953]: info: Aug 21 09:30:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:09 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 3117ms Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 3114ms Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 3113ms Aug 21 09:30:09 volumio volumio[953]: info: sendMpdCommand add "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" took 5 milliseconds Aug 21 09:30:09 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 09:30:09 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand play Aug 21 09:30:09 volumio volumio[953]: info: Aug 21 09:30:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:09 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:09 volumio volumio[953]: info: Aug 21 09:30:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:09 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 10ms Aug 21 09:30:09 volumio volumio[953]: info: sendMpdCommand play took 8 milliseconds Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 6ms Aug 21 09:30:09 volumio volumio[953]: info: ------------------------------ 6ms Aug 21 09:30:11 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:12 volumio volumio[953]: info: Aug 21 09:30:12 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:12 volumio volumio[953]: info: Aug 21 09:30:12 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:12 volumio volumio[953]: info: Aug 21 09:30:12 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand status took 12 milliseconds Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand status took 12 milliseconds Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand status took 15 milliseconds Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand clearerror took 25 milliseconds Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand clearerror took 24 milliseconds Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand clearerror took 19 milliseconds Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 21 09:30:12 volumio volumio[953]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:12 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:12 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:12 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: No code Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:12 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:12 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: No code Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:12 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:12 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: No code Aug 21 09:30:12 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:12 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:12 volumio volumio[953]: info: ------------------------------ 132ms Aug 21 09:30:12 volumio volumio[953]: info: ------------------------------ 131ms Aug 21 09:30:12 volumio volumio[953]: info: ------------------------------ 130ms Aug 21 09:30:18 volumio volumio[953]: info: CoreCommandRouter::volumioPlay Aug 21 09:30:18 volumio volumio[953]: info: CoreStateMachine::play index undefined Aug 21 09:30:18 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 09:30:18 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:18 volumio volumio[953]: info: CoreStateMachine::startPlaybackTimer Aug 21 09:30:18 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:18 volumio volumio[953]: info: [1724207418038] ControllerUPNPBrowser::clearAddPlayTrack Aug 21 09:30:18 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand stop Aug 21 09:30:18 volumio volumio[953]: info: sendMpdCommand stop took 1 milliseconds Aug 21 09:30:18 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clear Aug 21 09:30:18 volumio volumio[953]: info: sendMpdCommand clear took 1 milliseconds Aug 21 09:30:18 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" Aug 21 09:30:18 volumio volumio[953]: info: Aug 21 09:30:18 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:18 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:18 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:18 volumio volumio[953]: info: Aug 21 09:30:18 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:18 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:18 volumio volumio[953]: info: Aug 21 09:30:18 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:18 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:21 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" Aug 21 09:30:21 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:21 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:21 volumio volumio[953]: error: updateQueue error: null Aug 21 09:30:21 volumio volumio[953]: info: Aug 21 09:30:21 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:21 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 3145ms Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 3144ms Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 3143ms Aug 21 09:30:21 volumio volumio[953]: info: sendMpdCommand add "http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3" took 5 milliseconds Aug 21 09:30:21 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 09:30:21 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand play Aug 21 09:30:21 volumio volumio[953]: Upnp client error: Error: This socket has been ended by the other party Aug 21 09:30:21 volumio volumio[953]: info: Aug 21 09:30:21 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:21 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:21 volumio volumio[953]: info: Aug 21 09:30:21 volumio volumio[953]: ---------------------------- MPD announces system playlist update Aug 21 09:30:21 volumio volumio[953]: info: Ignoring MPD Status Update Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 11ms Aug 21 09:30:21 volumio volumio[953]: info: sendMpdCommand play took 7 milliseconds Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 6ms Aug 21 09:30:21 volumio volumio[953]: info: ------------------------------ 4ms Aug 21 09:30:24 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:24 volumio volumio[953]: info: Aug 21 09:30:24 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:24 volumio volumio[953]: info: Aug 21 09:30:24 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:24 volumio volumio[953]: info: Aug 21 09:30:24 volumio volumio[953]: ---------------------------- MPD announces state update: player Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand status took 13 milliseconds Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand status took 11 milliseconds Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand status took 11 milliseconds Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand clearerror took 16 milliseconds Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand clearerror took 17 milliseconds Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand clearerror took 15 milliseconds Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 21 09:30:24 volumio volumio[953]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:24 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:24 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:24 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: No code Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:24 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:24 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: No code Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:30:24 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:30:24 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: No code Aug 21 09:30:24 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:30:24 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:30:24 volumio volumio[953]: info: ------------------------------ 133ms Aug 21 09:30:24 volumio volumio[953]: info: ------------------------------ 130ms Aug 21 09:30:24 volumio volumio[953]: info: ------------------------------ 129ms Aug 21 09:30:30 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:36 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:42 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:30:42 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 09:30:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 21 09:30:43 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 21 09:30:43 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 21 09:30:43 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 21 09:30:43 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 21 09:30:43 volumio sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1496]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 21 09:30:43 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 21 09:30:43 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 21 09:30:43 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 21 09:30:43 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:43 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:43 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 21 09:30:43 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:30:47 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Aug 21 09:30:48 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:30:54 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:00 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:06 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:08 volumio volumio[953]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Aug 21 09:31:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Aug 21 09:31:08 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Aug 21 09:31:08 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:08 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:08 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Aug 21 09:31:08 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:08 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:08 volumio volumio[953]: info: Permissions for /etc/network/interfaces set Aug 21 09:31:08 volumio volumio[953]: info: Restarting Wired Network Aug 21 09:31:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 21 09:31:08 volumio volumio[953]: info: Discovery: Restarting Advertising due to device name change Aug 21 09:31:08 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Aug 21 09:31:08 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:08 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:08 volumio sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Aug 21 09:31:08 volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:08 volumio sudo[1518]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:08 volumio sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Aug 21 09:31:08 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:08 volumio kernel: lan78xx 1-1.1.1:1.0: Runtime PM usage count underflow! Aug 21 09:31:08 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Aug 21 09:31:08 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Aug 21 09:31:08 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:09 volumio volumio[953]: info: Discovery: A device disappeared from network Aug 21 09:31:09 volumio volumio[953]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 21 09:31:12 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:13 volumio volumio[953]: info: Discovery: Started advertising with name: volumio Aug 21 09:31:14 volumio volumio[953]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 21 09:31:18 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 21 09:31:18 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 21 09:31:18 volumio volumio[953]: info: Refreshing Cached IP Addresses Aug 21 09:31:18 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 21 09:31:18 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:18 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:18 volumio sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 21 09:31:18 volumio sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:18 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:18 volumio sudo[1567]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:18 volumio systemd[1]: upmpdcli.service: Succeeded. Aug 21 09:31:18 volumio sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 21 09:31:18 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:18 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:19 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:19 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 21 09:31:19 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 21 09:31:19 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 21 09:31:19 volumio volumio[953]: info: Received Get System Version Aug 21 09:31:19 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 21 09:31:24 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 09:31:24 volumio volumio[953]: info: Getting Alsa Cards List without I2S DAC Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 21 09:31:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 09:31:28 volumio sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 21 09:31:28 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:28 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:28 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 21 09:31:28 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:28 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:28 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 21 09:31:28 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 09:31:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 21 09:31:28 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Aug 21 09:31:28 volumio volumio[953]: info: Upmpdcli Daemon Started Aug 21 09:31:28 volumio volumio[1604]: writing RSA key Aug 21 09:31:30 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: info: Retrieving Cloud Streaming UI Aug 21 09:31:32 volumio volumio[953]: info: Getting Tidal Cloud Configuration Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: info: Getting Qobuz Cloud Configuration Aug 21 09:31:32 volumio volumio[953]: info: Asking plugin for UI Config Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: info: Getting Spotify Cloud Configuration Aug 21 09:31:32 volumio volumio[953]: info: Asking plugin for UI Config Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: info: Saving Spotify Acccount Aug 21 09:31:32 volumio volumio[953]: info: Got Tidal Cloud Configuration Aug 21 09:31:32 volumio volumio[953]: info: Got it Aug 21 09:31:32 volumio volumio[953]: info: Got it Aug 21 09:31:32 volumio volumio[953]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 09:31:32 volumio volumio[953]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Aug 21 09:31:32 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Aug 21 09:31:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 21 09:31:36 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:37 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:41 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand rescan Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand rescan took 5 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 12 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 10 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 9 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 8 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 6 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 6 milliseconds Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatetrue Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatetrue Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatetrue Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 62 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 42 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 207ms Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 203ms Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 202ms Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: Aug 21 09:31:42 volumio volumio[953]: ---------------------------- MPD announces state update: update Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::getState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 113 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 112 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 20 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 19 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 18 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand status took 17 milliseconds Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatefalse Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatefalse Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: info: Command Router : Notfying DB Updatefalse Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseState Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 57 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 21 09:31:42 volumio volumio[953]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ControllerMpd::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::servicePushState Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Aug 21 09:31:42 volumio volumio[953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","artist":null,"album":null,"uri":"http://192.168.0.131:10243/WMPNSSv4/1400114939/0_MTRfZmZjM2U0NTlfMmU1ZDI3NGFfZmM1YzJkZWMtMjk5OA.mp3","trackType":"mp3"} Aug 21 09:31:42 volumio volumio[953]: verbose: CURRENT POSITION 0 Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: No code Aug 21 09:31:42 volumio volumio[953]: info: CoreStateMachine::pushState Aug 21 09:31:42 volumio volumio[953]: info: CoreCommandRouter::volumioPushState Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 276ms Aug 21 09:31:42 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 187ms Aug 21 09:31:42 volumio volumio[953]: info: ------------------------------ 186ms Aug 21 09:31:45 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:48 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:49 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:53 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 21 09:31:54 volumio wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 09:31:56 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 21 09:31:56 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 21 09:31:57 volumio volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 21 09:31:57 volumio volumio[953]: Error: getaddrinfo -3007 Aug 21 09:31:57 volumio volumio[953]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 21 09:31:57 volumio volumio[953]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 21 09:31:57 volumio volumio[953]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 21 09:31:57 volumio volumio[953]: code: -3007, Aug 21 09:31:57 volumio volumio[953]: errno: -3007, Aug 21 09:31:57 volumio volumio[953]: syscall: 'getaddrinfo' Aug 21 09:31:57 volumio volumio[953]: } Aug 21 09:31:57 volumio volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 21 09:31:57 volumio sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-21 09:30 Aug 21 09:31:57 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"