-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-06-10 11:40:54 CST. -- Jun 10 11:39:00 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:01 volumio-rpi kernel: hwmon hwmon1: Undervoltage detected! Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Preload queue cleared Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::ClearQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::updateTrackBlock Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrackBlock Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::serviceStop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::serviceStop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::stop Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::clearPlayQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::saveQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::addQueueItems Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::addQueueItems Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Preload queue cleared Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Adding Item to queue: albums://BEYOND(%E6%B7%B1%E5%BA%A6%E9%9F%B3%E5%83%8F%E5%BA%97)/BEYOND%20Deliberate%E7%8A%B9%E8%B1%AB(%E8%B6%85%E8%B6%8A%E6%97%B6%E4%BB%A3%E7%BA%AA%E5%BF%B5%E7%89%88)(HiFi%E7%AB%8B%E4%BD%93%E7%8E%AF%E7%BB%95%E9%9F%B3%E8%B4%A8) Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Using cached record of: albums://BEYOND(%E6%B7%B1%E5%BA%A6%E9%9F%B3%E5%83%8F%E5%BA%97)/BEYOND%20Deliberate%E7%8A%B9%E8%B1%AB(%E8%B6%85%E8%B6%8A%E6%97%B6%E4%BB%A3%E7%BA%AA%E5%BF%B5%E7%89%88)(HiFi%E7%AB%8B%E4%BD%93%E7%8E%AF%E7%BB%95%E9%9F%B3%E8%B4%A8) Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::saveQueue Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::updateTrackBlock Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrackBlock Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPlay Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::play index 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::play index undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3 Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand stop took 121 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand stop took 28 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces system playlist update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Ignoring MPD Status Update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 26 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand clear took 25 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 23 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3" Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: 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} Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: No code Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 183ms Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces system playlist update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Ignoring MPD Status Update Jun 10 11:39:02 volumio-rpi volumio[1180]: error: updateQueue error: null Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces system playlist update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Ignoring MPD Status Update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 230ms Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 211 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand add "INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3" took 211 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 56ms Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jun 10 11:39:02 volumio-rpi volumio[1180]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 289ms Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces system playlist update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Ignoring MPD Status Update Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 98ms Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand play took 56 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 54ms Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 10 11:39:02 volumio-rpi volumio[1180]: info: touch_display: Setting screensaver timeout to 60 seconds. Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 28 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 28 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 25 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Jun 10 11:39:02 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: info: sendMpdCommand status took 11 milliseconds Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus stop Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Received an update from plugin. extracting info from payload Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:02 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:39:02 volumio-rpi volumio[1180]: info: Received an update from plugin. extracting info from payload Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:02 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:02 volumio-rpi volumio[1180]: info: ------------------------------ 173ms Jun 10 11:39:03 volumio-rpi volumio[1180]: info: ------------------------------ 266ms Jun 10 11:39:03 volumio-rpi volumio[1180]: info: ------------------------------ 260ms Jun 10 11:39:03 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 210 milliseconds Jun 10 11:39:03 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:03 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:03 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:03 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:39:03 volumio-rpi volumio[1180]: info: Received an update from plugin. extracting info from payload Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:03 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:03 volumio-rpi volumio[1180]: info: ------------------------------ 287ms Jun 10 11:39:03 volumio-rpi volumio[1180]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 10 11:39:03 volumio-rpi volumio[1180]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioSeek Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::seek Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: TRACKBLOCK {"uri":"music-library/INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","service":"mpd","name":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=214&web=BEYOND(%E6%B7%B1%E5%BA%A6%E9%9F%B3%E5%83%8F%E5%BA%97)/BEYOND%20Deliberate%E7%8A%B9%E8%B1%AB(%E8%B6%85%E8%B6%8A%E6%97%B6%E4%BB%A3%E7%BA%AA%E5%BF%B5%E7%89%88)(HiFi%E7%AB%8B%E4%BD%93%E7%8E%AF%E7%BB%95%E9%9F%B3%E8%B4%A8)/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96&metadata=false","duration":301,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ControllerMpd::seek Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:05 volumio-rpi volumio[1180]: error: null Jun 10 11:39:05 volumio-rpi volumio[1180]: info: Jun 10 11:39:05 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:05 volumio-rpi volumio[1180]: info: Jun 10 11:39:05 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:05 volumio-rpi volumio[1180]: info: sendMpdCommand status took 22 milliseconds Jun 10 11:39:05 volumio-rpi volumio[1180]: info: sendMpdCommand status took 19 milliseconds Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:05 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180000,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:39:05 volumio-rpi volumio[1180]: info: Received an update from plugin. extracting info from payload Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180000,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:05 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService play Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:39:05 volumio-rpi volumio[1180]: info: Received an update from plugin. extracting info from payload Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:05 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ------------------------------ 202ms Jun 10 11:39:05 volumio-rpi volumio[1180]: info: ------------------------------ 198ms Jun 10 11:39:05 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::servicePause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ControllerMpd::pause Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: sendMpdCommand pause took 6 milliseconds Jun 10 11:39:06 volumio-rpi volumio[1180]: info: Jun 10 11:39:06 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:06 volumio-rpi volumio[1180]: info: Jun 10 11:39:06 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: player Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:39:06 volumio-rpi volumio[1180]: info: sendMpdCommand status took 6 milliseconds Jun 10 11:39:06 volumio-rpi volumio[1180]: info: sendMpdCommand status took 4 milliseconds Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:39:06 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 11:39:06 volumio-rpi volumio[1180]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":181977,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":181977,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光辉岁月","artist":"BEYOND(深度音像店)","album":"BEYOND Deliberate犹豫(超越时代纪念版)(HiFi立体环绕音质)","uri":"INTERNAL/musics/20-BEYOND专辑 42首/024.光辉岁月 (国语)-BEYOND.mp3","trackType":"mp3"} Jun 10 11:39:06 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:06 volumio-rpi volumio[1180]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ------------------------------ 99ms Jun 10 11:39:06 volumio-rpi volumio[1180]: info: ------------------------------ 95ms Jun 10 11:39:06 volumio-rpi volumio[1180]: info: touch_display: Setting screensaver timeout to 60 seconds. Jun 10 11:39:09 volumio-rpi volumio[1180]: info: MyVolumio login type: Token Jun 10 11:39:11 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:12 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:39:12 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:16 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:39:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:39:18 volumio-rpi kernel: hwmon hwmon1: Voltage normalised Jun 10 11:39:22 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:24 volumio-rpi kernel: hwmon hwmon1: Undervoltage detected! Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Preparing to save Alsa Options, stopping services first Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPause Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreStateMachine::pause Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Updating Volume Controller Parameters: Device: 0 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Disabling external Volume Control Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Preparing to generate the ALSA configuration file Jun 10 11:39:27 volumio-rpi volumio[1180]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 11:39:27 volumio-rpi volumio[1180]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Reading ALSA contributions from plugins. Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:39:27 volumio-rpi volumio[1180]: info: VolumeController:: Volume=86 Mute =false Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:39:27 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:27 volumio-rpi volumio[1180]: info: Asound.conf file written Jun 10 11:39:27 volumio-rpi sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 10 11:39:27 volumio-rpi sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:27 volumio-rpi sudo[3165]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Output device has changed, restarting MPD Jun 10 11:39:28 volumio-rpi sudo[3170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:39:28 volumio-rpi sudo[3170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Output device has changed, restarting Shairport Sync Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:28 volumio-rpi sudo[3170]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:28 volumio-rpi sudo[3172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:39:28 volumio-rpi sudo[3172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi systemd[1]: Stopping Music Player Daemon... Jun 10 11:39:28 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Output device has changed, restarting MPD Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Output device has changed, restarting Shairport Sync Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:28 volumio-rpi sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:39:28 volumio-rpi sudo[3179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi sudo[3179]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:28 volumio-rpi sudo[3181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:39:28 volumio-rpi sudo[3181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:39:28 volumio-rpi volumio[1180]: info: MPD Permissions set Jun 10 11:39:28 volumio-rpi volumio[1180]: info: MPD Permissions set Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Starting Shairport Sync Jun 10 11:39:28 volumio-rpi systemd[1]: mpd.service: Succeeded. Jun 10 11:39:28 volumio-rpi systemd[1]: Stopped Music Player Daemon. Jun 10 11:39:28 volumio-rpi volumio[1180]: info: Starting Shairport Sync Jun 10 11:39:28 volumio-rpi systemd[1]: Starting Music Player Daemon... Jun 10 11:39:28 volumio-rpi sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:39:28 volumio-rpi sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi sudo[3200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:39:28 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:39:28 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:39:28 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:39:28 volumio-rpi sudo[3200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:39:28 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:39:29 volumio-rpi sudo[3197]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:29 volumio-rpi volumio[1180]: info: Shairport-Sync Started Jun 10 11:39:29 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:39:29 volumio-rpi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 10 11:39:29 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:39:29 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:39:29 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:39:29 volumio-rpi sudo[3200]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:29 volumio-rpi sudo[3196]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 11:39:29 volumio-rpi sudo[3196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:39:29 volumio-rpi sudo[3196]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:29 volumio-rpi volumio[1180]: (node:1180) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 10 11:39:29 volumio-rpi volumio[1180]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 10 11:39:29 volumio-rpi volumio[1180]: (node:1180) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 10 11:39:29 volumio-rpi volumio[1180]: (node:1180) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 10 11:39:29 volumio-rpi volumio[1180]: (node:1180) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 10 11:39:29 volumio-rpi volumio[1180]: (node:1180) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 10 11:39:29 volumio-rpi volumio[1180]: info: Shairport-Sync Started Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreStateMachine::getcurrentVolume Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:39:31 volumio-rpi volumio[1180]: info: VolumeController:: Volume=86 Mute =false Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreStateMachine::updateTrackBlock Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrackBlock Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:39:31 volumio-rpi volumio[1180]: info: VolumeController:: Volume=86 Mute =false Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:31 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:39:31 volumio-rpi mpd[3205]: Jun 10 11:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 11:39:31 volumio-rpi systemd[1]: Started Music Player Daemon. Jun 10 11:39:31 volumio-rpi sudo[3181]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:31 volumio-rpi sudo[3172]: pam_unix(sudo:session): session closed for user root Jun 10 11:39:32 volumio-rpi volumio[1180]: error: MPD error: The expression evaluated to a falsy value: Jun 10 11:39:32 volumio-rpi volumio[1180]: assert.ok(self.idling) Jun 10 11:39:32 volumio-rpi volumio[1180]: error: The expression evaluated to a falsy value: Jun 10 11:39:32 volumio-rpi volumio[1180]: assert.ok(self.idling) Jun 10 11:39:32 volumio-rpi volumio[1180]: error: updateQueue error: null Jun 10 11:39:33 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:38 volumio-rpi kernel: hwmon hwmon1: Voltage normalised Jun 10 11:39:39 volumio-rpi volumio[1180]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 10 11:39:39 volumio-rpi volumio[1180]: info: Retrying Login Due to Network Error Jun 10 11:39:39 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:45 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPlay Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CoreStateMachine::play index undefined Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:39:45 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:45 volumio-rpi volumio[1180]: info: ControllerMpd::resume Jun 10 11:39:45 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jun 10 11:39:45 volumio-rpi volumio[1180]: info: sendMpdCommand play took 3 milliseconds Jun 10 11:39:48 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:39:48 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:39:49 volumio-rpi volumio[1180]: info: MyVolumio login type: Token Jun 10 11:39:50 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:39:56 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPlay Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CoreStateMachine::play index undefined Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:40:01 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:01 volumio-rpi volumio[1180]: info: ControllerMpd::resume Jun 10 11:40:01 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jun 10 11:40:01 volumio-rpi volumio[1180]: info: sendMpdCommand play took 8 milliseconds Jun 10 11:40:01 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:07 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPlay Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CoreStateMachine::play index undefined Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:40:12 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:12 volumio-rpi volumio[1180]: info: ControllerMpd::resume Jun 10 11:40:12 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jun 10 11:40:12 volumio-rpi volumio[1180]: info: sendMpdCommand play took 1 milliseconds Jun 10 11:40:13 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:17 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:40:17 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:18 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:19 volumio-rpi volumio[1180]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 10 11:40:19 volumio-rpi volumio[1180]: info: Retrying Login Due to Network Error Jun 10 11:40:20 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:40:20 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Retrieving Cloud Streaming UI Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Getting Tidal Cloud Configuration Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Getting Qobuz Cloud Configuration Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Asking plugin for UI Config Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Getting Spotify Cloud Configuration Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Asking plugin for UI Config Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Saving Spotify Acccount Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Got Tidal Cloud Configuration Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Got it Jun 10 11:40:23 volumio-rpi volumio[1180]: info: Got it Jun 10 11:40:23 volumio-rpi volumio[1180]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:40:23 volumio-rpi volumio[1180]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:40:23 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:40:24 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:24 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:24 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 10 11:40:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:28 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand rescan Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:28 volumio-rpi volumio[1180]: info: Jun 10 11:40:28 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: update Jun 10 11:40:28 volumio-rpi volumio[1180]: info: sendMpdCommand rescan took 9 milliseconds Jun 10 11:40:28 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:28 volumio-rpi volumio[1180]: info: Jun 10 11:40:28 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: update Jun 10 11:40:28 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:28 volumio-rpi volumio[1180]: info: sendMpdCommand status took 9 milliseconds Jun 10 11:40:28 volumio-rpi volumio[1180]: info: sendMpdCommand status took 7 milliseconds Jun 10 11:40:28 volumio-rpi volumio[1180]: info: sendMpdCommand status took 5 milliseconds Jun 10 11:40:28 volumio-rpi volumio[1180]: info: sendMpdCommand status took 4 milliseconds Jun 10 11:40:28 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: Command Router : Notfying DB Updatetrue Jun 10 11:40:29 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:29 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: Command Router : Notfying DB Updatetrue Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:40:29 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: 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} Jun 10 11:40:29 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: 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} Jun 10 11:40:29 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:29 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:29 volumio-rpi volumio[1180]: info: ------------------------------ 975ms Jun 10 11:40:29 volumio-rpi volumio[1180]: info: ------------------------------ 972ms Jun 10 11:40:29 volumio-rpi volumio[1180]: info: MyVolumio login type: Token Jun 10 11:40:29 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:32 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:32 volumio-rpi volumio[1180]: info: MPD Database updated - AlbumList cache refreshed Jun 10 11:40:32 volumio-rpi volumio[1180]: info: Jun 10 11:40:32 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: database Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: Jun 10 11:40:32 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: update Jun 10 11:40:32 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: MPD Database updated - AlbumList cache refreshed Jun 10 11:40:32 volumio-rpi volumio[1180]: info: Jun 10 11:40:32 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: database Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: Jun 10 11:40:32 volumio-rpi volumio[1180]: ---------------------------- MPD announces state update: update Jun 10 11:40:32 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: ControllerMpd::getState Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:40:32 volumio-rpi volumio[1180]: info: sendMpdCommand status took 87 milliseconds Jun 10 11:40:32 volumio-rpi volumio[1180]: info: sendMpdCommand status took 86 milliseconds Jun 10 11:40:32 volumio-rpi volumio[1180]: info: sendMpdCommand status took 85 milliseconds Jun 10 11:40:32 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: Command Router : Notfying DB Updatefalse Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: 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} Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: 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} Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ------------------------------ 560ms Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ------------------------------ 561ms Jun 10 11:40:33 volumio-rpi volumio[1180]: info: sendMpdCommand status took 562 milliseconds Jun 10 11:40:33 volumio-rpi volumio[1180]: info: sendMpdCommand status took 562 milliseconds Jun 10 11:40:33 volumio-rpi volumio[1180]: info: sendMpdCommand status took 562 milliseconds Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: Command Router : Notfying DB Updatefalse Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: ControllerMpd::parseState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: 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} Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ControllerMpd::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::servicePushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: 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} Jun 10 11:40:33 volumio-rpi volumio[1180]: verbose: CURRENT POSITION 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState stateService stop Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreStateMachine::pushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:33 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioPushState Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ------------------------------ 830ms Jun 10 11:40:33 volumio-rpi volumio[1180]: info: ------------------------------ 832ms Jun 10 11:40:35 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:36 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:40 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:41 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:41 volumio-rpi kernel: hwmon hwmon1: Undervoltage detected! Jun 10 11:40:44 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:46 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:47 volumio-rpi kernel: hwmon hwmon1: Voltage normalised Jun 10 11:40:48 volumio-rpi volumio[1180]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:40:51 volumio-rpi volumio[1180]: info: CoreCommandRouter::volumioGetState Jun 10 11:40:51 volumio-rpi volumio[1180]: info: CorePlayQueue::getTrack 0 Jun 10 11:40:52 volumio-rpi volumio[1180]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 11:40:52 volumio-rpi volumio[1180]: Error: connect ETIMEDOUT 128.242.240.93:80 Jun 10 11:40:52 volumio-rpi volumio[1180]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 10 11:40:52 volumio-rpi volumio[1180]: errno: -110, Jun 10 11:40:52 volumio-rpi volumio[1180]: code: 'ETIMEDOUT', Jun 10 11:40:52 volumio-rpi volumio[1180]: syscall: 'connect', Jun 10 11:40:52 volumio-rpi volumio[1180]: address: '128.242.240.93', Jun 10 11:40:52 volumio-rpi volumio[1180]: port: 80 Jun 10 11:40:52 volumio-rpi volumio[1180]: } Jun 10 11:40:52 volumio-rpi volumio[1180]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 11:40:52 volumio-rpi wpa_supplicant[922]: wlan0: Failed to initiate sched scan Jun 10 11:40:54 volumio-rpi sudo[3470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 11:39 Jun 10 11:40:54 volumio-rpi sudo[3470]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"