-- Logs begin at Tue 2024-08-27 17:01:02 CST, end at Tue 2024-08-27 17:09:32 CST. -- Aug 27 17:08:04 volumio volumio[1135]: info: MyVolumio login type: Token Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 17:08:08 volumio volumio[1135]: info: Discovery: Getting this device information Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioNext Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::next Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::stop Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::serviceStop Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::stop Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:08:08 volumio volumio[1135]: info: Aug 27 17:08:08 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand stop took 56 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::play index undefined Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/Kiroro - 長い間.flac Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Aug 27 17:08:08 volumio volumio[1135]: info: Aug 27 17:08:08 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:08 volumio volumio[1135]: info: Aug 27 17:08:08 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:08 volumio volumio[1135]: info: Aug 27 17:08:08 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand status took 33 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand stop took 25 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand status took 18 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand status took 11 milliseconds Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:08 volumio volumio[1135]: info: Aug 27 17:08:08 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:08 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand status took 25 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand clear took 17 milliseconds Aug 27 17:08:08 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/Kiroro - 長い間.flac" Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:08 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:08 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: 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":"未来へ","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 未来へ.flac","trackType":"flac"} Aug 27 17:08:08 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::syncState stateService stop Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:08 volumio volumio[1135]: info: No code Aug 27 17:08:08 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:08 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:08 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:08 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 27 17:08:08 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 164ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 148ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 143ms Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: error: updateQueue error: null Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 155ms Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 146 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand add "USB/Kiroro - 長い間.flac" took 145 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 38ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 34ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 30ms Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Aug 27 17:08:09 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 187ms Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Aug 27 17:08:09 volumio volumio[1135]: info: Ignoring MPD Status Update Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 75ms Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand play took 74 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 67ms Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 66ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 53ms Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 45 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 43 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 42 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 26 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 23 milliseconds Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: Aug 27 17:08:09 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 41 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 36 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 35 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 31 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 25 milliseconds Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 362ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 634ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 626ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 618ms Aug 27 17:08:09 volumio volumio[1135]: info: ------------------------------ 623ms Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand status took 594 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 567 milliseconds Aug 27 17:08:09 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 573 milliseconds Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:08:09 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:09 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:09 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:09 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:10 volumio volumio[1135]: info: ------------------------------ 860ms Aug 27 17:08:10 volumio volumio[1135]: info: ------------------------------ 858ms Aug 27 17:08:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 334 milliseconds Aug 27 17:08:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:08:10 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:08:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:08:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:08:10 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:08:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Aug 27 17:08:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:08:10 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Aug 27 17:08:10 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:10 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:08:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:08:10 volumio volumio[1135]: info: ------------------------------ 1008ms Aug 27 17:08:14 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:08:14 volumio volumio[1135]: info: CURURI: music-library Aug 27 17:08:14 volumio volumio[1135]: info: Preload queue cleared Aug 27 17:08:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:08:18 volumio volumio[1135]: info: CURURI: playlists Aug 27 17:08:18 volumio volumio[1135]: info: Listing playlists Aug 27 17:08:18 volumio volumio[1135]: info: Preload queue cleared Aug 27 17:08:25 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:08:25 volumio volumio[1135]: info: CURURI: albums:// Aug 27 17:08:25 volumio volumio[1135]: info: listAlbums - loading Albums from cache Aug 27 17:08:25 volumio volumio[1135]: info: Preload queue cleared Aug 27 17:08:26 volumio volumio[1135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Aug 27 17:08:27 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 27 17:08:27 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:08:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:08:30 volumio volumio[1135]: info: CURURI: albums://THE%E8%99%8E%E8%88%9E%E7%AB%9C/%E3%83%AD%E3%83%BC%E3%83%89~%E3%83%89%E3%82%AD%E3%83%A5%E3%83%A1%E3%83%B3%E3%83%88 Aug 27 17:08:30 volumio volumio[1135]: info: Preload queue cleared Aug 27 17:08:30 volumio volumio[1135]: info: Preloading song: music-library/USB/THE虎舞竜 - ロード.flac Aug 27 17:08:30 volumio volumio[1135]: info: Exploding uri music-library/USB/THE虎舞竜 - ロード.flac in service mpd Aug 27 17:08:30 volumio volumio[1135]: info: ALBUMART /albumart?cacheid=851&web=THE%E8%99%8E%E8%88%9E%E7%AB%9C/%E3%83%AD%E3%83%BC%E3%83%89~%E3%83%89%E3%82%AD%E3%83%A5%E3%83%A1%E3%83%B3%E3%83%88/extralarge&path=%2Fmnt%2FUSB%2FTHE%E8%99%8E%E8%88%9E%E7%AB%9C%20-%20%E3%83%AD%E3%83%BC%E3%83%89.flac&metadata=false Aug 27 17:08:30 volumio volumio[1135]: info: URI /mnt/USB/THE虎舞竜 - ロード.flac Aug 27 17:08:30 volumio volumio[1135]: info: No valid Plugin REST Endpoint Aug 27 17:08:30 volumio volumio[1135]: info: No valid Plugin REST Endpoint Aug 27 17:08:34 volumio volumio[1135]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 27 17:08:34 volumio volumio[1135]: info: Retrying Login Due to Network Error Aug 27 17:08:37 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 17:08:37 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 17:08:37 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:08:37 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 17:08:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 17:08:44 volumio volumio[1135]: info: MyVolumio login type: Token Aug 27 17:09:14 volumio volumio[1135]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 27 17:09:14 volumio volumio[1135]: info: Retrying Login Due to Network Error Aug 27 17:09:24 volumio volumio[1135]: info: MyVolumio login type: Token Aug 27 17:09:30 volumio volumio[1135]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 27 17:09:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 27 17:09:30 volumio volumio[1135]: info: Preparing to save Alsa Options, stopping services first Aug 27 17:09:30 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 27 17:09:30 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:30 volumio volumio[1135]: info: CoreCommandRouter::volumioPause Aug 27 17:09:30 volumio volumio[1135]: info: CoreStateMachine::pause Aug 27 17:09:30 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:09:30 volumio volumio[1135]: info: CoreStateMachine::servicePause Aug 27 17:09:30 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:30 volumio volumio[1135]: info: CoreCommandRouter::servicePause Aug 27 17:09:30 volumio volumio[1135]: info: ControllerMpd::pause Aug 27 17:09:30 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand pause Aug 27 17:09:30 volumio volumio[1135]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Aug 27 17:09:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 17:09:31 volumio volumio[1135]: info: Found match in Cards Database: setting mixer for card Analog Out Aug 27 17:09:31 volumio volumio[1135]: info: Setting mixer Master for card Analog Out Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 17:09:31 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 17:09:31 volumio volumio[1135]: info: Disabling external Volume Control Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 17:09:31 volumio volumio[1135]: info: Preparing to generate the ALSA configuration file Aug 27 17:09:31 volumio volumio[1135]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 27 17:09:31 volumio volumio[1135]: info: Reading ALSA contributions from plugins. Aug 27 17:09:31 volumio volumio[1135]: info: Aug 27 17:09:31 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand pause took 430 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:09:31 volumio volumio[1135]: info: Aug 27 17:09:31 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:09:31 volumio volumio[1135]: info: Aug 27 17:09:31 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:09:31 volumio volumio[1135]: info: Aug 27 17:09:31 volumio volumio[1135]: ---------------------------- MPD announces state update: player Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::getState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand status took 48 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand status took 49 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand status took 44 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand status took 42 milliseconds Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseState Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 36 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 35 milliseconds Aug 27 17:09:31 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:09:31 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82584,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4925 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:09:31 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82584,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4925 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:09:31 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82584,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4925 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:09:31 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:09:31 volumio volumio[1135]: info: ControllerMpd::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82584,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4925 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Aug 27 17:09:31 volumio volumio[1135]: verbose: CURRENT POSITION 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::pushState Aug 27 17:09:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 27 17:09:31 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:09:31 volumio volumio[1135]: info: ------------------------------ 254ms Aug 27 17:09:31 volumio volumio[1135]: info: ------------------------------ 249ms Aug 27 17:09:31 volumio volumio[1135]: info: ------------------------------ 247ms Aug 27 17:09:31 volumio volumio[1135]: info: ------------------------------ 245ms Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 17:09:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 17:09:31 volumio volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:09:31 volumio volumio[1135]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Aug 27 17:09:31 volumio volumio[1135]: at Object.readdirSync (fs.js:1048:3) Aug 27 17:09:31 volumio volumio[1135]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Aug 27 17:09:31 volumio volumio[1135]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Aug 27 17:09:31 volumio volumio[1135]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Aug 27 17:09:31 volumio volumio[1135]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Aug 27 17:09:31 volumio volumio[1135]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Aug 27 17:09:31 volumio volumio[1135]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 27 17:09:31 volumio volumio[1135]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Aug 27 17:09:31 volumio volumio[1135]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Aug 27 17:09:31 volumio volumio[1135]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 27 17:09:31 volumio volumio[1135]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 27 17:09:31 volumio volumio[1135]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Aug 27 17:09:31 volumio volumio[1135]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 27 17:09:31 volumio volumio[1135]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 27 17:09:31 volumio volumio[1135]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Aug 27 17:09:31 volumio volumio[1135]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Aug 27 17:09:31 volumio volumio[1135]: errno: -20, Aug 27 17:09:31 volumio volumio[1135]: syscall: 'scandir', Aug 27 17:09:31 volumio volumio[1135]: code: 'ENOTDIR', Aug 27 17:09:31 volumio volumio[1135]: path: '/tmp/peppyrunning' Aug 27 17:09:31 volumio volumio[1135]: } Aug 27 17:09:31 volumio volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:09:32 volumio sudo[3206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 17:08 Aug 27 17:09:32 volumio sudo[3206]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"