-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Thu 2025-03-13 17:56:38 JST. -- Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:03 volumio volumio[1042]: info: Prefetching next song Mar 13 17:55:03 volumio volumio[1042]: info: DOING PREFETCH IN MPD Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand add "NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3" Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:03 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand add "NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3" took 2 milliseconds Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:03 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:03 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces state update: options Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 5ms Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand consume 1 took 4 milliseconds Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 3ms Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 2ms Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces state update: options Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:03 volumio volumio[1042]: info: Mar 13 17:55:03 volumio volumio[1042]: ---------------------------- MPD announces state update: options Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:03 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:55:03 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198960,"duration":204,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"ひこうき雲","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/01 ひこうき雲.mp3","trackType":"mp3"} Mar 13 17:55:03 volumio volumio[1042]: verbose: CURRENT POSITION 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:03 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198960,"duration":204,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"ひこうき雲","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/01 ひこうき雲.mp3","trackType":"mp3"} Mar 13 17:55:03 volumio volumio[1042]: verbose: CURRENT POSITION 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:03 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198960,"duration":204,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"ひこうき雲","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/01 ひこうき雲.mp3","trackType":"mp3"} Mar 13 17:55:03 volumio volumio[1042]: verbose: CURRENT POSITION 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:03 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:03 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 17:55:03 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 43ms Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 42ms Mar 13 17:55:03 volumio volumio[1042]: info: ------------------------------ 41ms Mar 13 17:55:07 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 17:55:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:07 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 17:55:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:08 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:08 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 17:55:08 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 17:55:08 volumio volumio[1042]: info: Mar 13 17:55:08 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 7ms Mar 13 17:55:08 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 4ms Mar 13 17:55:08 volumio volumio[1042]: info: sendMpdCommand status took 3 milliseconds Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 3ms Mar 13 17:55:08 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:55:08 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:55:08 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:55:08 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:08 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:55:08 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:08 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:55:08 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 17:55:08 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:55:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:55:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 40ms Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 39ms Mar 13 17:55:08 volumio volumio[1042]: info: ------------------------------ 38ms Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioPause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::servicePause Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::servicePause Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::pause Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand pause Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand pause took 1 milliseconds Mar 13 17:56:17 volumio volumio[1042]: info: Mar 13 17:56:17 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:56:17 volumio volumio[1042]: info: Mar 13 17:56:17 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:56:17 volumio volumio[1042]: info: Mar 13 17:56:17 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand status took 1 milliseconds Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseState Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68857,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:56:17 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState stateService pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68857,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:56:17 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState stateService pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 17:56:17 volumio volumio[1042]: info: ------------------------------ 18ms Mar 13 17:56:17 volumio volumio[1042]: info: ------------------------------ 18ms Mar 13 17:56:17 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 13 17:56:17 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo Mar 13 17:56:17 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68857,"duration":173,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"恋のスーパーパラシューター","artist":"荒井由実","album":"Super Best Of Yumi Arai [Disc 1]","uri":"NAS/WIN-3EGI0LSDSU8/荒井由実/Super Best Of Yumi Arai [Disc 1]/02 恋のスーパーパラシューター.mp3","trackType":"mp3"} Mar 13 17:56:17 volumio volumio[1042]: verbose: CURRENT POSITION 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState stateService pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus pause Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 17:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 17:56:17 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 17:56:17 volumio volumio[1042]: info: ------------------------------ 26ms Mar 13 17:56:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 17:56:20 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 17:56:28 volumio volumio[1042]: info: Enabling plugin peppy_screensaver Mar 13 17:56:28 volumio volumio[1042]: info: Loading plugin "peppy_screensaver"... Mar 13 17:56:29 volumio volumio[1042]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 13 17:56:29 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file Mar 13 17:56:29 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 13 17:56:29 volumio volumio[1042]: info: Asound.conf file unchanged, so no further update is needed Mar 13 17:56:29 volumio volumio[1042]: info: Output device has changed, restarting MPD Mar 13 17:56:29 volumio sudo[2665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:56:29 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:29 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:56:29 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 17:56:29 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:56:29 volumio volumio[1042]: info: PLUGIN START: peppy_screensaver Mar 13 17:56:29 volumio volumio[1042]: info: Loading i18n strings for locale ja Mar 13 17:56:29 volumio systemd[1]: mpd.service: Succeeded. Mar 13 17:56:29 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 17:56:29 volumio volumio[1042]: info: /tmp/myfifo created Mar 13 17:56:29 volumio volumio[1042]: info: /tmp/myfifosa created Mar 13 17:56:29 volumio sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 13 17:56:29 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio volumio[1042]: info: snd-dummy loaded Mar 13 17:56:29 volumio systemd[1]: Starting Music Player Daemon... Mar 13 17:56:29 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:29 volumio sudo[2674]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:56:29 volumio sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio sudo[2674]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:56:29 volumio volumio[1042]: info: Done. Mar 13 17:56:29 volumio volumio[1042]: info: MPD Permissions set Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Mar 13 17:56:29 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: Starting Shairport Sync Mar 13 17:56:29 volumio volumio[1042]: info: Starting Shairport Sync Mar 13 17:56:29 volumio sudo[2707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 17:56:29 volumio sudo[2707]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 17:56:29 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 17:56:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:29 volumio sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 17:56:29 volumio sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 13 17:56:29 volumio sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 13 17:56:29 volumio sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:29 volumio sudo[2718]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio sudo[2707]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio volumio[1042]: info: Shairport-Sync Started Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:56:29 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 17:56:29 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 17:56:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:29 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:29 volumio sudo[2711]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:29 volumio volumio[1042]: info: Shairport-Sync Started Mar 13 17:56:29 volumio volumio[1042]: info: MPD Permissions set Mar 13 17:56:30 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 13 17:56:30 volumio mpd[2701]: Mar 13 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 17:56:30 volumio sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:56:30 volumio sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:30 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 17:56:30 volumio systemd[1]: mpd.service: Succeeded. Mar 13 17:56:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 17:56:30 volumio systemd[1]: Starting Music Player Daemon... Mar 13 17:56:30 volumio sudo[2737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:56:30 volumio sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:30 volumio sudo[2737]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:31 volumio mpd[2740]: Mar 13 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 17:56:31 volumio systemd[1]: Started Music Player Daemon. Mar 13 17:56:31 volumio sudo[2733]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:31 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:31 volumio volumio[1042]: error: MPD error: The expression evaluated to a falsy value: Mar 13 17:56:31 volumio volumio[1042]: assert.ok(self.idling) Mar 13 17:56:31 volumio volumio[1042]: error: The expression evaluated to a falsy value: Mar 13 17:56:31 volumio volumio[1042]: assert.ok(self.idling) Mar 13 17:56:31 volumio volumio[1042]: error: updateQueue error: null Mar 13 17:56:32 volumio volumio[1042]: info: Enabling plugin peppymeterbasic Mar 13 17:56:32 volumio volumio[1042]: info: Loading plugin "peppymeterbasic"... Mar 13 17:56:32 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file Mar 13 17:56:32 volumio volumio[1042]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 13 17:56:32 volumio volumio[1042]: info: Reading ALSA contributions from plugins. Mar 13 17:56:32 volumio volumio[1042]: info: Asound.conf file written Mar 13 17:56:32 volumio sudo[2744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 17:56:32 volumio sudo[2744]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:32 volumio sudo[2744]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:32 volumio volumio[1042]: info: Output device has changed, restarting MPD Mar 13 17:56:32 volumio sudo[2749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:56:32 volumio sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:32 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:32 volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:32 volumio sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:56:32 volumio sudo[2751]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:32 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 17:56:32 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:56:32 volumio volumio[1042]: info: PLUGIN START: peppymeterbasic Mar 13 17:56:32 volumio systemd[1]: mpd.service: Succeeded. Mar 13 17:56:32 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 13 17:56:32 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file Mar 13 17:56:32 volumio volumio[1042]: info: Done. Mar 13 17:56:32 volumio volumio[1042]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 13 17:56:32 volumio volumio[1042]: info: Reading ALSA contributions from plugins. Mar 13 17:56:32 volumio volumio[1042]: info: MPD Permissions set Mar 13 17:56:32 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio systemd[1]: Starting Music Player Daemon... Mar 13 17:56:33 volumio volumio[1042]: info: Starting Shairport Sync Mar 13 17:56:33 volumio sudo[2763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 17:56:33 volumio sudo[2763]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio volumio[1042]: info: Asound.conf file unchanged, so no further update is needed Mar 13 17:56:33 volumio volumio[1042]: info: Output device has changed, restarting MPD Mar 13 17:56:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 17:56:33 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 17:56:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:33 volumio sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:56:33 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:33 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio sudo[2763]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio sudo[2757]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:56:33 volumio sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio sudo[2757]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio sudo[2771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:56:33 volumio sudo[2771]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:56:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 13 17:56:33 volumio systemd[1]: mpd.service: Succeeded. Mar 13 17:56:33 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 17:56:33 volumio systemd[1]: Starting Music Player Daemon... Mar 13 17:56:33 volumio volumio[1042]: info: MPD Permissions set Mar 13 17:56:33 volumio volumio[1042]: info: Shairport-Sync Started Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: Starting Shairport Sync Mar 13 17:56:33 volumio sudo[2788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 17:56:33 volumio sudo[2779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:56:33 volumio sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:33 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 17:56:33 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 17:56:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 17:56:33 volumio sudo[2788]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio volumio[1042]: info: Shairport-Sync Started Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:56:33 volumio mpd[2791]: Mar 13 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 17:56:33 volumio systemd[1]: Started Music Player Daemon. Mar 13 17:56:33 volumio sudo[2751]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio sudo[2771]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:33 volumio volumio[1042]: error: MPD error: The expression evaluated to a falsy value: Mar 13 17:56:33 volumio volumio[1042]: assert.ok(self.idling) Mar 13 17:56:33 volumio volumio[1042]: error: The expression evaluated to a falsy value: Mar 13 17:56:33 volumio volumio[1042]: assert.ok(self.idling) Mar 13 17:56:33 volumio volumio[1042]: error: updateQueue error: null Mar 13 17:56:34 volumio volumio[1042]: info: Enabling plugin touch_display Mar 13 17:56:34 volumio volumio[1042]: info: Loading plugin "touch_display"... Mar 13 17:56:35 volumio volumio[1042]: info: Applying required configuration parameters for plugin touch_display Mar 13 17:56:35 volumio volumio[1042]: info: PLUGIN START: touch_display Mar 13 17:56:35 volumio volumio[1042]: info: Loading i18n strings for locale ja Mar 13 17:56:35 volumio volumio[1042]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 13 17:56:35 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 17:56:35 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 13 17:56:35 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 13 17:56:35 volumio sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio volumio[1042]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 13 17:56:35 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 13 17:56:35 volumio sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 13 17:56:35 volumio sudo[2804]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 13 17:56:35 volumio systemd[1]: Reloading. Mar 13 17:56:35 volumio sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 13 17:56:35 volumio sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio sudo[2808]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 13 17:56:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 13 17:56:35 volumio volumio[1042]: info: Enabling plugin touch_display Mar 13 17:56:35 volumio volumio[1042]: info: Loading plugin "touch_display"... Mar 13 17:56:35 volumio volumio[1042]: info: Applying required configuration parameters for plugin touch_display Mar 13 17:56:35 volumio volumio[1042]: info: PLUGIN START: touch_display Mar 13 17:56:35 volumio volumio[1042]: info: Loading i18n strings for locale ja Mar 13 17:56:35 volumio volumio[1042]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 13 17:56:35 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 17:56:35 volumio sudo[2824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 13 17:56:35 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio volumio[1042]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 13 17:56:35 volumio sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 13 17:56:35 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 13 17:56:35 volumio sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 13 17:56:35 volumio sudo[2833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 13 17:56:35 volumio sudo[2833]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:35 volumio sudo[2833]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:35 volumio systemd[1]: Reloading. Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 13 17:56:35 volumio sudo[2804]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:35 volumio volumio[1042]: info: touch_display: systemctl daemon-reload succeeded. Mar 13 17:56:35 volumio sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 13 17:56:35 volumio sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 13 17:56:36 volumio sudo[2802]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:36 volumio volumio[1042]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 13 17:56:36 volumio systemd[1]: Reloading. Mar 13 17:56:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 13 17:56:36 volumio systemd[1]: Reloading. Mar 13 17:56:36 volumio sudo[2830]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:36 volumio volumio[1042]: info: touch_display: systemctl daemon-reload succeeded. Mar 13 17:56:36 volumio sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 13 17:56:36 volumio sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:56:37 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 13 17:56:37 volumio sudo[2826]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 13 17:56:37 volumio systemd[1]: Started Volumio Kiosk. Mar 13 17:56:37 volumio sudo[2836]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: Volumio Kiosk started. Mar 13 17:56:37 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Mar 13 17:56:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:37 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:37 volumio volumio[1042]: info: Done. Mar 13 17:56:37 volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Mar 13 17:56:37 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Mar 13 17:56:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: Volumio Kiosk started. Mar 13 17:56:37 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Mar 13 17:56:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:37 volumio volumio[1042]: info: Done. Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 13 17:56:37 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Mar 13 17:56:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: Mar 13 17:56:37 volumio startx[2894]: X.Org X Server 1.20.4 Mar 13 17:56:37 volumio startx[2894]: X Protocol Version 11, Revision 0 Mar 13 17:56:37 volumio startx[2894]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 13 17:56:37 volumio startx[2894]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Mar 13 17:56:37 volumio startx[2894]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=512 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:3C:77:25 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=617582c8-95f3-4515-be73-3f2524a1cc80 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 13 17:56:37 volumio startx[2894]: Build Date: 04 April 2023 07:50:56AM Mar 13 17:56:37 volumio startx[2894]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 13 17:56:37 volumio startx[2894]: Current version of pixman: 0.36.0 Mar 13 17:56:37 volumio startx[2894]: Before reporting problems, check http://wiki.x.org Mar 13 17:56:37 volumio startx[2894]: to make sure that you have the latest version. Mar 13 17:56:37 volumio startx[2894]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 13 17:56:37 volumio startx[2894]: (++) from command line, (!!) notice, (II) informational, Mar 13 17:56:37 volumio startx[2894]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 13 17:56:37 volumio startx[2894]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Mar 13 17:56:37 2025 Mar 13 17:56:37 volumio startx[2894]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 13 17:56:37 volumio startx[2894]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:37 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: X display number found: 0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 13 17:56:38 volumio volumio[1042]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 13 17:56:38 volumio volumio[1042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 17:56:38 volumio volumio[1042]: Error: connect EISCONN /tmp/.X11-unix/X0 Mar 13 17:56:38 volumio volumio[1042]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 13 17:56:38 volumio volumio[1042]: errno: -106, Mar 13 17:56:38 volumio volumio[1042]: code: 'EISCONN', Mar 13 17:56:38 volumio volumio[1042]: syscall: 'connect', Mar 13 17:56:38 volumio volumio[1042]: address: '/tmp/.X11-unix/X0' Mar 13 17:56:38 volumio volumio[1042]: } Mar 13 17:56:38 volumio volumio[1042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 17:56:38 volumio sudo[3000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 17:55 Mar 13 17:56:38 volumio sudo[3000]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"