-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2024-09-03 15:19:43 CST. -- Sep 03 15:18:01 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 03 15:18:02 volumio volumio[802]: info: CoreCommandRouter::Close All Modals sent Sep 03 15:18:09 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 15:18:09 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:11 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:11 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:22 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:22 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:24 volumio volumio[802]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 15:18:24 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:25 volumio volumio[802]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 03 15:18:25 volumio volumio[802]: info: Retrying Login Due to Network Error Sep 03 15:18:33 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:33 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:35 volumio volumio[802]: info: MyVolumio login type: Token Sep 03 15:18:42 volumio volumio[802]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Sep 03 15:18:42 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Sep 03 15:18:42 volumio volumio[802]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 15:18:42 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 15:18:44 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:44 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:55 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:55 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:56 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:18:56 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:57 volumio volumio[802]: info: CoreCommandRouter::volumioPlay Sep 03 15:18:57 volumio volumio[802]: info: CoreStateMachine::play index undefined Sep 03 15:18:57 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 15:18:57 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:57 volumio volumio[802]: info: CoreStateMachine::startPlaybackTimer Sep 03 15:18:57 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:57 volumio volumio[802]: verbose: ControllerMpd::clearAddPlayTracks USB/Kiroro - 長い間.flac Sep 03 15:18:57 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 15:18:57 volumio volumio[802]: info: sendMpdCommand stop took 3 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand clear took 2 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand add "USB/Kiroro - 長い間.flac" Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand add "USB/Kiroro - 長い間.flac" took 18 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 18ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 14ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 12ms Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand play Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces system playlist update Sep 03 15:18:58 volumio volumio[802]: info: Ignoring MPD Status Update Sep 03 15:18:58 volumio volumio[802]: error: Upnp client error: Error: This socket has been ended by the other party Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 34ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 33ms Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand play took 25 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 23ms Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 22ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 19ms Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 84 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 144 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 141 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 136 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: Sep 03 15:18:58 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 47 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 30 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 17 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 14 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand status took 11 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: 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"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus stop Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: 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"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: 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"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: 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"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 287ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 353ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 349ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 346ms Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 175 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 171 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 171 milliseconds Sep 03 15:18:58 volumio volumio[802]: info: sendMpdCommand playlistinfo took 170 milliseconds Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5238 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5238 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5238 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":758,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5238 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:18:58 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Sep 03 15:18:58 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:18:58 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:18:58 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 385ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 364ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 361ms Sep 03 15:18:58 volumio volumio[802]: info: ------------------------------ 358ms Sep 03 15:19:05 volumio volumio[802]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 03 15:19:05 volumio volumio[802]: info: Retrying Login Due to Network Error Sep 03 15:19:06 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:19:06 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:06 volumio sudo[1438]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Sep 03 15:19:06 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 15:19:14 volumio volumio[802]: 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: 6 Sep 03 15:19:14 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Sep 03 15:19:14 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:15 volumio volumio[802]: info: MyVolumio login type: Token Sep 03 15:19:17 volumio kernel: perf: interrupt took too long (2537 > 2500), lowering kernel.perf_event_max_sample_rate to 78750 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::volumioPause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::servicePause Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::servicePause Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::pause Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand pause Sep 03 15:19:42 volumio volumio[802]: info: Sep 03 15:19:42 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand pause took 6 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:19:42 volumio volumio[802]: info: Sep 03 15:19:42 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:19:42 volumio volumio[802]: info: Sep 03 15:19:42 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:19:42 volumio volumio[802]: info: Sep 03 15:19:42 volumio volumio[802]: ---------------------------- MPD announces state update: player Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::getState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand status took 22 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand status took 15 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand status took 11 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand status took 8 milliseconds Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseState Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 15:19:42 volumio volumio[802]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:19:42 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44874,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4908 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:19:42 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44874,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4908 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:19:42 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44874,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4908 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:19:42 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Sep 03 15:19:42 volumio volumio[802]: info: ControllerMpd::pushState Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::servicePushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44874,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4908 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ～Kiroro no Mori～","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Sep 03 15:19:42 volumio volumio[802]: verbose: CURRENT POSITION 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState stateService pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus pause Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::pushState Sep 03 15:19:42 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Sep 03 15:19:42 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Sep 03 15:19:42 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Sep 03 15:19:42 volumio volumio[802]: info: ------------------------------ 180ms Sep 03 15:19:42 volumio volumio[802]: info: ------------------------------ 173ms Sep 03 15:19:42 volumio volumio[802]: info: ------------------------------ 167ms Sep 03 15:19:42 volumio volumio[802]: info: ------------------------------ 163ms Sep 03 15:19:42 volumio volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 15:19:42 volumio volumio[802]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Sep 03 15:19:42 volumio volumio[802]: at Object.readdirSync (fs.js:1048:3) Sep 03 15:19:42 volumio volumio[802]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Sep 03 15:19:42 volumio volumio[802]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Sep 03 15:19:42 volumio volumio[802]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Sep 03 15:19:42 volumio volumio[802]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Sep 03 15:19:42 volumio volumio[802]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Sep 03 15:19:42 volumio volumio[802]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 03 15:19:42 volumio volumio[802]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Sep 03 15:19:42 volumio volumio[802]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Sep 03 15:19:42 volumio volumio[802]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 03 15:19:42 volumio volumio[802]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 03 15:19:42 volumio volumio[802]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Sep 03 15:19:42 volumio volumio[802]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 03 15:19:42 volumio volumio[802]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 03 15:19:42 volumio volumio[802]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Sep 03 15:19:42 volumio volumio[802]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Sep 03 15:19:42 volumio volumio[802]: errno: -20, Sep 03 15:19:42 volumio volumio[802]: syscall: 'scandir', Sep 03 15:19:42 volumio volumio[802]: code: 'ENOTDIR', Sep 03 15:19:42 volumio volumio[802]: path: '/tmp/peppyrunning' Sep 03 15:19:42 volumio volumio[802]: } Sep 03 15:19:42 volumio volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 15:19:43 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 15:18 Sep 03 15:19:43 volumio sudo[1601]: 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"