-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-10-13 14:50:27 CEST. -- Oct 13 14:49:08 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:08 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::volumioSeek Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::seek Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/_SSeek/1/Bresh, Pinguini Tattici Nucleari - Nightmares.mp3","service":"mpd","name":"Nightmares","artist":"Bresh, Pinguini Tattici Nucleari","album":"Nightmares","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=377&web=Bresh%2C%20Pinguini%20Tattici%20Nucleari/Nightmares/extralarge&path=%2FNAS%2FNAS%2F_SSeek%2F1&metadata=false","duration":202,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: ControllerMpd::seek Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:11 nappali volumio[760]: error: null Oct 13 14:49:11 nappali volumio[760]: info: Oct 13 14:49:11 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:11 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:11 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:11 nappali volumio[760]: info: sendMpdCommand status took 68 milliseconds Oct 13 14:49:11 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:11 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:11 nappali volumio[760]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 14:49:11 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:11 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39000,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nightmares","artist":"Bresh, Pinguini Tattici Nucleari","album":"Nightmares","uri":"NAS/NAS/_SSeek/1/Bresh, Pinguini Tattici Nucleari - Nightmares.mp3","trackType":"mp3"} Oct 13 14:49:11 nappali volumio[760]: verbose: CURRENT POSITION 17 Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:11 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:11 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:11 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:11 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:11 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:11 nappali volumio[760]: info: ------------------------------ 100ms Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:11 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::volumioSeek Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::seek Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/_SSeek/1/Bresh, Pinguini Tattici Nucleari - Nightmares.mp3","service":"mpd","name":"Nightmares","artist":"Bresh, Pinguini Tattici Nucleari","album":"Nightmares","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=377&web=Bresh%2C%20Pinguini%20Tattici%20Nucleari/Nightmares/extralarge&path=%2FNAS%2FNAS%2F_SSeek%2F1&metadata=false","duration":202,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: info: ControllerMpd::seek Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:13 nappali volumio[760]: error: null Oct 13 14:49:13 nappali volumio[760]: info: Oct 13 14:49:13 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:13 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:13 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:13 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:13 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:13 nappali volumio[760]: info: sendMpdCommand status took 33 milliseconds Oct 13 14:49:13 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:13 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:13 nappali volumio[760]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 13 14:49:13 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:13 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":79000,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nightmares","artist":"Bresh, Pinguini Tattici Nucleari","album":"Nightmares","uri":"NAS/NAS/_SSeek/1/Bresh, Pinguini Tattici Nucleari - Nightmares.mp3","trackType":"mp3"} Oct 13 14:49:13 nappali volumio[760]: verbose: CURRENT POSITION 17 Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:13 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:13 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:14 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:14 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:14 nappali volumio[760]: info: ------------------------------ 81ms Oct 13 14:49:14 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:14 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:14 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:14 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:14 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 17 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: Oct 13 14:49:15 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand stop took 24 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/Simply Red a Song for You YT.flac Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand status took 48 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand stop took 46 milliseconds Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:15 nappali volumio[760]: info: Oct 13 14:49:15 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:15 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand clear took 6 milliseconds Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/Simply Red a Song for You YT.flac" Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nightmares","artist":"Bresh, Pinguini Tattici Nucleari","album":"Nightmares","uri":"NAS/NAS/_SSeek/1/Bresh, Pinguini Tattici Nucleari - Nightmares.mp3","trackType":"mp3"} Oct 13 14:49:15 nappali volumio[760]: verbose: CURRENT POSITION 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: No code Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: ------------------------------ 88ms Oct 13 14:49:15 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:15 nappali volumio[760]: info: Oct 13 14:49:15 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:15 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:15 nappali volumio[760]: info: ------------------------------ 51ms Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/Simply Red a Song for You YT.flac" took 49 milliseconds Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:15 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:15 nappali volumio[760]: info: ------------------------------ 13ms Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand play took 11 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:15 nappali volumio[760]: info: Oct 13 14:49:15 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: Oct 13 14:49:15 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand status took 278 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand status took 6 milliseconds Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Red a Song for You YT.flac","artist":null,"album":null,"uri":"NAS/NAS/_SSeek/Simply Red a Song for You YT.flac","trackType":"flac"} Oct 13 14:49:15 nappali volumio[760]: verbose: CURRENT POSITION 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:15 nappali volumio[760]: info: ------------------------------ 294ms Oct 13 14:49:15 nappali volumio[760]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 13 14:49:15 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:15 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1504,"duration":248,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"Simply Red a Song for You YT.flac","artist":null,"album":null,"uri":"NAS/NAS/_SSeek/Simply Red a Song for You YT.flac","trackType":"flac"} Oct 13 14:49:15 nappali volumio[760]: verbose: CURRENT POSITION 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:15 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:15 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:15 nappali volumio[760]: info: ------------------------------ 48ms Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:15 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:16 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:16 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:16 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:16 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:16 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrack 181 Oct 13 14:49:16 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:16 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:16 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:16 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:16 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:16 nappali volumio[760]: info: Oct 13 14:49:16 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:16 nappali volumio[760]: info: sendMpdCommand stop took 35 milliseconds Oct 13 14:49:16 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:16 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:16 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac Oct 13 14:49:16 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:16 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:16 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:16 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand status took 48 milliseconds Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand stop took 46 milliseconds Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:17 nappali volumio[760]: info: Oct 13 14:49:17 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:17 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand clear took 4 milliseconds Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac" Oct 13 14:49:17 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Red a Song for You YT.flac","artist":null,"album":null,"uri":"NAS/NAS/_SSeek/Simply Red a Song for You YT.flac","trackType":"flac"} Oct 13 14:49:17 nappali volumio[760]: verbose: CURRENT POSITION 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:17 nappali volumio[760]: info: No code Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:17 nappali volumio[760]: info: ------------------------------ 110ms Oct 13 14:49:17 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:17 nappali volumio[760]: info: Oct 13 14:49:17 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:17 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:17 nappali volumio[760]: info: ------------------------------ 80ms Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac" took 78 milliseconds Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:17 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:17 nappali volumio[760]: info: Oct 13 14:49:17 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:17 nappali volumio[760]: info: ------------------------------ 38ms Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand play took 35 milliseconds Oct 13 14:49:17 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:17 nappali volumio[760]: info: Oct 13 14:49:17 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand status took 68 milliseconds Oct 13 14:49:17 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand status took 57 milliseconds Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:17 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Never Mind (Acoustic)","artist":"bshp","album":"Pure Acoustic 2022","uri":"NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac","trackType":"flac"} Oct 13 14:49:17 nappali volumio[760]: verbose: CURRENT POSITION 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:17 nappali volumio[760]: info: ------------------------------ 138ms Oct 13 14:49:17 nappali volumio[760]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 13 14:49:17 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:17 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"715 Kbps","isStreaming":false,"title":"Never Mind (Acoustic)","artist":"bshp","album":"Pure Acoustic 2022","uri":"NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac","trackType":"flac"} Oct 13 14:49:17 nappali volumio[760]: verbose: CURRENT POSITION 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:17 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:17 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:17 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:17 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:17 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:17 nappali volumio[760]: info: ------------------------------ 124ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:17 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:17 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 14:49:18 nappali volumio[760]: info: Discovery: Getting this device information Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:18 nappali volumio[760]: info: Listing playlists Oct 13 14:49:18 nappali volumio[760]: info: Listing playlists Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 162 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: Oct 13 14:49:18 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand stop took 33 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3 Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand status took 53 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand stop took 50 milliseconds Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:18 nappali volumio[760]: info: Oct 13 14:49:18 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:18 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand clear took 13 milliseconds Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3" Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Never Mind (Acoustic)","artist":"bshp","album":"Pure Acoustic 2022","uri":"NAS/NAS/_SSeek/BSHP - Never Mind (Acoustic).flac","trackType":"flac"} Oct 13 14:49:18 nappali volumio[760]: verbose: CURRENT POSITION 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: No code Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: ------------------------------ 114ms Oct 13 14:49:18 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:18 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:18 nappali volumio[760]: info: Oct 13 14:49:18 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:18 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:18 nappali volumio[760]: info: ------------------------------ 61ms Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3" took 60 milliseconds Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:18 nappali volumio[760]: info: ------------------------------ 30ms Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand play took 29 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:18 nappali volumio[760]: info: Oct 13 14:49:18 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:18 nappali volumio[760]: info: Oct 13 14:49:18 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand status took 21 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand status took 6 milliseconds Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"We Can Always Come Back To This","artist":"Brian Tyree Henry","album":"2021 Emmy Awards","uri":"NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3","trackType":"mp3"} Oct 13 14:49:18 nappali volumio[760]: verbose: CURRENT POSITION 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:18 nappali volumio[760]: info: ------------------------------ 44ms Oct 13 14:49:18 nappali volumio[760]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 13 14:49:18 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:18 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"We Can Always Come Back To This","artist":"Brian Tyree Henry","album":"2021 Emmy Awards","uri":"NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3","trackType":"mp3"} Oct 13 14:49:18 nappali volumio[760]: verbose: CURRENT POSITION 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:18 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:18 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:18 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:18 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:18 nappali volumio[760]: info: ------------------------------ 68ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:18 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:18 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:26 nappali volumio[760]: info: VolumeController::SetAlsaVolume83 Oct 13 14:49:26 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:26 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:26 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:26 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:26 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:26 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:26 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:26 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:26 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 18 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: Oct 13 14:49:28 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand stop took 45 milliseconds Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand status took 61 milliseconds Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand stop took 57 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:28 nappali volumio[760]: info: Oct 13 14:49:28 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:28 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand clear took 6 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac" Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"We Can Always Come Back To This","artist":"Brian Tyree Henry","album":"2021 Emmy Awards","uri":"NAS/NAS/_SSeek/1/Brian Tyree Henry - We Can Always Come Back To This.mp3","trackType":"mp3"} Oct 13 14:49:28 nappali volumio[760]: verbose: CURRENT POSITION 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: No code Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: ------------------------------ 133ms Oct 13 14:49:28 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:28 nappali volumio[760]: info: Oct 13 14:49:28 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:28 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:28 nappali volumio[760]: info: ------------------------------ 77ms Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac" took 72 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:28 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:28 nappali volumio[760]: info: ------------------------------ 75ms Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand play took 74 milliseconds Oct 13 14:49:28 nappali volumio[760]: info: Oct 13 14:49:28 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:28 nappali volumio[760]: info: Oct 13 14:49:28 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand status took 40 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand status took 13 milliseconds Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"584 Kbps","isStreaming":false,"title":"Crazy","artist":"Daniela Andrade","album":"Crazy","uri":"NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac","trackType":"flac"} Oct 13 14:49:28 nappali volumio[760]: verbose: CURRENT POSITION 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:28 nappali volumio[760]: info: ------------------------------ 60ms Oct 13 14:49:28 nappali volumio[760]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 13 14:49:28 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:28 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"629 Kbps","isStreaming":false,"title":"Crazy","artist":"Daniela Andrade","album":"Crazy","uri":"NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac","trackType":"flac"} Oct 13 14:49:28 nappali volumio[760]: verbose: CURRENT POSITION 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:28 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:28 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:28 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:28 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:28 nappali volumio[760]: info: ------------------------------ 105ms Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:28 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:28 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 29 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: Oct 13 14:49:30 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand stop took 29 milliseconds Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3 Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand status took 48 milliseconds Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand stop took 44 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:30 nappali volumio[760]: info: Oct 13 14:49:30 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:30 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand clear took 12 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3" Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy","artist":"Daniela Andrade","album":"Crazy","uri":"NAS/NAS/_SSeek/1/Daniela Andrade - Crazy.flac","trackType":"flac"} Oct 13 14:49:30 nappali volumio[760]: verbose: CURRENT POSITION 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: No code Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: ------------------------------ 97ms Oct 13 14:49:30 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:30 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:30 nappali volumio[760]: info: Oct 13 14:49:30 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:30 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:30 nappali volumio[760]: info: ------------------------------ 47ms Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3" took 45 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:30 nappali volumio[760]: info: ------------------------------ 23ms Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand play took 22 milliseconds Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:30 nappali volumio[760]: info: Oct 13 14:49:30 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:30 nappali volumio[760]: info: Oct 13 14:49:30 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand status took 58 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand status took 15 milliseconds Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1248,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Navigare","artist":"Jovanotti","album":"Oh, vita!","uri":"NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3","trackType":"mp3"} Oct 13 14:49:30 nappali volumio[760]: verbose: CURRENT POSITION 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:30 nappali volumio[760]: info: ------------------------------ 76ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:30 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:30 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:30 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1374,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Navigare","artist":"Jovanotti","album":"Oh, vita!","uri":"NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3","trackType":"mp3"} Oct 13 14:49:30 nappali volumio[760]: verbose: CURRENT POSITION 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:30 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:30 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:30 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:30 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:30 nappali volumio[760]: info: ------------------------------ 79ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:30 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:30 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 81 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: Oct 13 14:49:32 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand stop took 38 milliseconds Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand status took 46 milliseconds Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand stop took 44 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:32 nappali volumio[760]: info: Oct 13 14:49:32 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:32 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand clear took 5 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac" Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Navigare","artist":"Jovanotti","album":"Oh, vita!","uri":"NAS/NAS/_SSeek/1/Jovanotti - Navigare.mp3","trackType":"mp3"} Oct 13 14:49:32 nappali volumio[760]: verbose: CURRENT POSITION 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: No code Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: ------------------------------ 95ms Oct 13 14:49:32 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:32 nappali volumio[760]: info: Oct 13 14:49:32 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:32 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:32 nappali volumio[760]: info: ------------------------------ 59ms Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac" took 56 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:32 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:32 nappali volumio[760]: info: ------------------------------ 32ms Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand play took 30 milliseconds Oct 13 14:49:32 nappali volumio[760]: info: Oct 13 14:49:32 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:32 nappali volumio[760]: info: Oct 13 14:49:32 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand status took 63 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand status took 27 milliseconds Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"Our Love (Original Mix)","artist":"Blank & Jones","album":"Relax Edition 11","uri":"NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac","trackType":"flac"} Oct 13 14:49:32 nappali volumio[760]: verbose: CURRENT POSITION 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:32 nappali volumio[760]: info: ------------------------------ 96ms Oct 13 14:49:32 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:32 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:32 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1352,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"519 Kbps","isStreaming":false,"title":"Our Love (Original Mix)","artist":"Blank & Jones","album":"Relax Edition 11","uri":"NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac","trackType":"flac"} Oct 13 14:49:32 nappali volumio[760]: verbose: CURRENT POSITION 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:32 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:32 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:32 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:32 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:32 nappali volumio[760]: info: ------------------------------ 66ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:32 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:32 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::volumioSeek Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::seek Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac","service":"mpd","name":"Our Love (Original Mix)","artist":"Blank & Jones","album":"Relax Edition 11","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=377&web=Blank%20%26%20Jones/Relax%20Edition%2011/extralarge&path=%2FNAS%2FNAS%2F_SSeek%2F1&metadata=false","duration":190,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: info: ControllerMpd::seek Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:34 nappali volumio[760]: error: null Oct 13 14:49:34 nappali volumio[760]: info: Oct 13 14:49:34 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:34 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:34 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:34 nappali volumio[760]: info: sendMpdCommand status took 14 milliseconds Oct 13 14:49:34 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:34 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:34 nappali volumio[760]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 13 14:49:34 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:34 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42000,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"Our Love (Original Mix)","artist":"Blank & Jones","album":"Relax Edition 11","uri":"NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac","trackType":"flac"} Oct 13 14:49:34 nappali volumio[760]: verbose: CURRENT POSITION 14 Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:34 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:34 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:34 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:34 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:34 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:34 nappali volumio[760]: info: ------------------------------ 101ms Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:34 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 14 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: Oct 13 14:49:36 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand stop took 33 milliseconds Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand status took 37 milliseconds Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand stop took 36 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:36 nappali volumio[760]: info: Oct 13 14:49:36 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:36 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand clear took 11 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac" Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Our Love (Original Mix)","artist":"Blank & Jones","album":"Relax Edition 11","uri":"NAS/NAS/_SSeek/1/Blank & Jones - Our Love (Original Mix).flac","trackType":"flac"} Oct 13 14:49:36 nappali volumio[760]: verbose: CURRENT POSITION 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: No code Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: ------------------------------ 95ms Oct 13 14:49:36 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:36 nappali volumio[760]: info: Oct 13 14:49:36 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:36 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:36 nappali volumio[760]: info: ------------------------------ 57ms Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac" took 55 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:36 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:36 nappali volumio[760]: info: ------------------------------ 25ms Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand play took 25 milliseconds Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:36 nappali volumio[760]: info: Oct 13 14:49:36 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:36 nappali volumio[760]: info: Oct 13 14:49:36 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand status took 11 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand status took 154 milliseconds Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand playlistinfo took 151 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Use Me (Brutal Hearts)","artist":"Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies","album":"Use Me (Brutal Hearts)","uri":"NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac","trackType":"flac"} Oct 13 14:49:36 nappali volumio[760]: verbose: CURRENT POSITION 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:36 nappali volumio[760]: info: ------------------------------ 170ms Oct 13 14:49:36 nappali volumio[760]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 13 14:49:36 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:36 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Use Me (Brutal Hearts)","artist":"Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies","album":"Use Me (Brutal Hearts)","uri":"NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac","trackType":"flac"} Oct 13 14:49:36 nappali volumio[760]: verbose: CURRENT POSITION 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:36 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:36 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:36 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:36 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:36 nappali volumio[760]: info: ------------------------------ 180ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:36 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:36 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:38 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:38 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:38 nappali volumio[760]: info: Listing playlists Oct 13 14:49:38 nappali volumio[760]: info: Listing playlists Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 38 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: Oct 13 14:49:47 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand stop took 27 milliseconds Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand status took 53 milliseconds Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand stop took 51 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:47 nappali volumio[760]: info: Oct 13 14:49:47 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:47 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand clear took 4 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac" Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Use Me (Brutal Hearts)","artist":"Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies","album":"Use Me (Brutal Hearts)","uri":"NAS/NAS/_SSeek/1/Diplo, Sturgill Simpson, Dove Cameron, Johnny Blue Skies - Use Me (Brutal Hearts).flac","trackType":"flac"} Oct 13 14:49:47 nappali volumio[760]: verbose: CURRENT POSITION 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: No code Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: ------------------------------ 81ms Oct 13 14:49:47 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:47 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:47 nappali volumio[760]: info: Oct 13 14:49:47 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:47 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:47 nappali volumio[760]: info: ------------------------------ 56ms Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac" took 54 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:47 nappali volumio[760]: info: ------------------------------ 8ms Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand play took 7 milliseconds Oct 13 14:49:47 nappali volumio[760]: info: Oct 13 14:49:47 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:47 nappali volumio[760]: info: Oct 13 14:49:47 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand status took 4 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand status took 71 milliseconds Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand playlistinfo took 70 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"Hands Up","artist":"Merk & Kremont","album":"Estate Anni '10 successi e canzoni","uri":"NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac","trackType":"flac"} Oct 13 14:49:47 nappali volumio[760]: verbose: CURRENT POSITION 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:47 nappali volumio[760]: info: ------------------------------ 82ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:47 nappali volumio[760]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 13 14:49:47 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:47 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"475 Kbps","isStreaming":false,"title":"Hands Up","artist":"Merk & Kremont","album":"Estate Anni '10 successi e canzoni","uri":"NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac","trackType":"flac"} Oct 13 14:49:47 nappali volumio[760]: verbose: CURRENT POSITION 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:47 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:47 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:47 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:47 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:47 nappali volumio[760]: info: ------------------------------ 115ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:47 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:47 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:48 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 14:49:48 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 14:49:48 nappali volumio[760]: info: Discovery: Getting this device information Oct 13 14:49:48 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:48 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:48 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 14:49:48 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:48 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 118 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: Oct 13 14:49:50 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand stop took 38 milliseconds Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand status took 39 milliseconds Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand stop took 37 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:50 nappali volumio[760]: info: Oct 13 14:49:50 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:50 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand clear took 11 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac" Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hands Up","artist":"Merk & Kremont","album":"Estate Anni '10 successi e canzoni","uri":"NAS/NAS/_SSeek/1/Merk & Kremont - Hands Up.flac","trackType":"flac"} Oct 13 14:49:50 nappali volumio[760]: verbose: CURRENT POSITION 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: No code Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: ------------------------------ 87ms Oct 13 14:49:50 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:50 nappali volumio[760]: info: Oct 13 14:49:50 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:50 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:50 nappali volumio[760]: info: ------------------------------ 45ms Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac" took 43 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:50 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:50 nappali volumio[760]: info: ------------------------------ 44ms Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand play took 42 milliseconds Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: Oct 13 14:49:50 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:50 nappali volumio[760]: info: Oct 13 14:49:50 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand status took 7 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand status took 12 milliseconds Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":733,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"823 Kbps","isStreaming":false,"title":"Freakin Out","artist":"Maya Fadeeva & Club Des Belugas","album":"That's My Style","uri":"NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac","trackType":"flac"} Oct 13 14:49:50 nappali volumio[760]: verbose: CURRENT POSITION 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:50 nappali volumio[760]: info: ------------------------------ 31ms Oct 13 14:49:50 nappali volumio[760]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 13 14:49:50 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:50 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"859 Kbps","isStreaming":false,"title":"Freakin Out","artist":"Maya Fadeeva & Club Des Belugas","album":"That's My Style","uri":"NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac","trackType":"flac"} Oct 13 14:49:50 nappali volumio[760]: verbose: CURRENT POSITION 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:50 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:50 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:50 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:50 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:50 nappali volumio[760]: info: ------------------------------ 81ms Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:50 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:50 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioSeek Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::seek Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac","service":"mpd","name":"Freakin Out","artist":"Maya Fadeeva & Club Des Belugas","album":"That's My Style","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=377&web=Maya%20Fadeeva%20%26%20Club%20Des%20Belugas/That's%20My%20Style/extralarge&path=%2FNAS%2FNAS%2F_SSeek%2F1&metadata=false","duration":206,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: ControllerMpd::seek Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:53 nappali volumio[760]: error: null Oct 13 14:49:53 nappali volumio[760]: info: Oct 13 14:49:53 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:53 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:53 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:53 nappali volumio[760]: info: sendMpdCommand status took 2 milliseconds Oct 13 14:49:53 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:53 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:53 nappali volumio[760]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 13 14:49:53 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:53 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92000,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1024 Kbps","isStreaming":false,"title":"Freakin Out","artist":"Maya Fadeeva & Club Des Belugas","album":"That's My Style","uri":"NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac","trackType":"flac"} Oct 13 14:49:53 nappali volumio[760]: verbose: CURRENT POSITION 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:53 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:53 nappali volumio[760]: info: ------------------------------ 57ms Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:53 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:53 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:53 nappali volumio[760]: info: CorePlayQueue::getTrack 115 Oct 13 14:49:53 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:53 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:53 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:53 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: Oct 13 14:49:54 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand stop took 25 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand status took 52 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand stop took 50 milliseconds Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:54 nappali volumio[760]: info: Oct 13 14:49:54 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:54 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand clear took 9 milliseconds Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac" Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Freakin Out","artist":"Maya Fadeeva & Club Des Belugas","album":"That's My Style","uri":"NAS/NAS/_SSeek/1/Maya Fadeeva & Club Des Belugas - Freakin Out.flac","trackType":"flac"} Oct 13 14:49:54 nappali volumio[760]: verbose: CURRENT POSITION 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:54 nappali volumio[760]: info: No code Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:54 nappali volumio[760]: info: ------------------------------ 92ms Oct 13 14:49:54 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:54 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:54 nappali volumio[760]: info: Oct 13 14:49:54 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:54 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:54 nappali volumio[760]: info: ------------------------------ 42ms Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac" took 40 milliseconds Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:54 nappali volumio[760]: info: ------------------------------ 20ms Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand play took 19 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:54 nappali volumio[760]: info: Oct 13 14:49:54 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:54 nappali volumio[760]: info: Oct 13 14:49:54 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand status took 95 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand status took 13 milliseconds Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":362,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Whiter Shade Of Pale","artist":"Chie Ayado","album":"Female Voices_Vol.129","uri":"NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac","trackType":"flac"} Oct 13 14:49:54 nappali volumio[760]: verbose: CURRENT POSITION 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:54 nappali volumio[760]: info: ------------------------------ 113ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:54 nappali volumio[760]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 13 14:49:54 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:54 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1248,"duration":362,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1163 Kbps","isStreaming":false,"title":"A Whiter Shade Of Pale","artist":"Chie Ayado","album":"Female Voices_Vol.129","uri":"NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac","trackType":"flac"} Oct 13 14:49:54 nappali volumio[760]: verbose: CURRENT POSITION 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:54 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:54 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:54 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:54 nappali volumio[760]: info: ------------------------------ 62ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:54 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:54 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:54 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:54 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:54 nappali volumio[760]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 13 14:49:54 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 26 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: Oct 13 14:49:56 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand stop took 63 milliseconds Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3 Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand status took 47 milliseconds Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand stop took 45 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:56 nappali volumio[760]: info: Oct 13 14:49:56 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:56 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand clear took 15 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3" Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Whiter Shade Of Pale","artist":"Chie Ayado","album":"Female Voices_Vol.129","uri":"NAS/NAS/_SSeek/1/Chie Ayado - A Whiter Shade Of Pale.flac","trackType":"flac"} Oct 13 14:49:56 nappali volumio[760]: verbose: CURRENT POSITION 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: No code Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: ------------------------------ 97ms Oct 13 14:49:56 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:56 nappali volumio[760]: info: Oct 13 14:49:56 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:56 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:56 nappali volumio[760]: info: ------------------------------ 45ms Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3" took 41 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:56 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:56 nappali volumio[760]: info: ------------------------------ 43ms Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand play took 42 milliseconds Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:56 nappali volumio[760]: info: Oct 13 14:49:56 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:56 nappali volumio[760]: info: Oct 13 14:49:56 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand status took 5 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand status took 55 milliseconds Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":141,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Comptine d'un autre été, l'après-midi","artist":"Yann Tiersen","album":"Amelie From Montmartre (original Soundtrack)","uri":"NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3","trackType":"mp3"} Oct 13 14:49:56 nappali volumio[760]: verbose: CURRENT POSITION 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:56 nappali volumio[760]: info: ------------------------------ 69ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:56 nappali volumio[760]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 13 14:49:56 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:56 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1064,"duration":141,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Comptine d'un autre été, l'après-midi","artist":"Yann Tiersen","album":"Amelie From Montmartre (original Soundtrack)","uri":"NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3","trackType":"mp3"} Oct 13 14:49:56 nappali volumio[760]: verbose: CURRENT POSITION 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:56 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:56 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:56 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:56 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:56 nappali volumio[760]: info: ------------------------------ 115ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:56 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:56 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:49:58 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:49:58 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:58 nappali volumio[760]: info: Listing playlists Oct 13 14:49:58 nappali volumio[760]: info: Listing playlists Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioNext Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::next Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 158 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:59 nappali volumio[760]: info: Oct 13 14:49:59 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand stop took 47 milliseconds Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::play index undefined Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/_SSeek/1/Ayo - Better Days.flac Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand status took 63 milliseconds Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand stop took 61 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:49:59 nappali volumio[760]: info: Oct 13 14:49:59 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:59 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand clear took 20 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/_SSeek/1/Ayo - Better Days.flac" Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Comptine d'un autre été, l'après-midi","artist":"Yann Tiersen","album":"Amelie From Montmartre (original Soundtrack)","uri":"NAS/NAS/_SSeek/1/Yann Tiersen - Comptine d'un autre été, l'après.mp3","trackType":"mp3"} Oct 13 14:49:59 nappali volumio[760]: verbose: CURRENT POSITION 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: No code Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: ------------------------------ 145ms Oct 13 14:49:59 nappali volumio[760]: error: updateQueue error: null Oct 13 14:49:59 nappali volumio[760]: info: Oct 13 14:49:59 nappali volumio[760]: ---------------------------- MPD announces system playlist update Oct 13 14:49:59 nappali volumio[760]: info: Ignoring MPD Status Update Oct 13 14:49:59 nappali volumio[760]: info: ------------------------------ 68ms Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand add "NAS/NAS/_SSeek/1/Ayo - Better Days.flac" took 62 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:49:59 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:49:59 nappali volumio[760]: info: ------------------------------ 50ms Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand play took 46 milliseconds Oct 13 14:49:59 nappali volumio[760]: info: Oct 13 14:49:59 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:59 nappali volumio[760]: info: Oct 13 14:49:59 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand status took 13 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand status took 20 milliseconds Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1123,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"Better Days","artist":"Ayo","album":"Gravity at Last","uri":"NAS/NAS/_SSeek/1/Ayo - Better Days.flac","trackType":"flac"} Oct 13 14:49:59 nappali volumio[760]: verbose: CURRENT POSITION 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:49:59 nappali volumio[760]: info: ------------------------------ 37ms Oct 13 14:49:59 nappali volumio[760]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:49:59 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:49:59 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"Better Days","artist":"Ayo","album":"Gravity at Last","uri":"NAS/NAS/_SSeek/1/Ayo - Better Days.flac","trackType":"flac"} Oct 13 14:49:59 nappali volumio[760]: verbose: CURRENT POSITION 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState stateService play Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:49:59 nappali volumio[760]: info: Received an update from plugin. extracting info from payload Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:49:59 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:49:59 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:49:59 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:49:59 nappali volumio[760]: info: ------------------------------ 48ms Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:49:59 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:00 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:50:00 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:50:00 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 14:50:00 nappali volumio[760]: info: [GPIO_Control] *** musicPlay *** Oct 13 14:50:00 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:00 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 On (musicPlay) Oct 13 14:50:08 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:50:08 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioClearQueue Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::ClearQueue Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::updateTrackBlock Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrackBlock Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::serviceStop Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::serviceStop Oct 13 14:50:13 nappali volumio[760]: info: ControllerMpd::stop Oct 13 14:50:13 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::clearPlayQueue Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::saveQueue Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:50:13 nappali volumio[760]: info: Oct 13 14:50:13 nappali volumio[760]: ---------------------------- MPD announces state update: player Oct 13 14:50:13 nappali volumio[760]: info: sendMpdCommand stop took 86 milliseconds Oct 13 14:50:13 nappali volumio[760]: info: ControllerMpd::getState Oct 13 14:50:13 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:50:13 nappali volumio[760]: info: sendMpdCommand status took 13 milliseconds Oct 13 14:50:13 nappali volumio[760]: verbose: ControllerMpd::parseState Oct 13 14:50:13 nappali volumio[760]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:50:13 nappali volumio[760]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 13 14:50:13 nappali volumio[760]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 13 14:50:13 nappali volumio[760]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:50:13 nappali volumio[760]: info: ControllerMpd::pushState Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::servicePushState Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Better Days","artist":"Ayo","album":"Gravity at Last","uri":"NAS/NAS/_SSeek/1/Ayo - Better Days.flac","trackType":"flac"} Oct 13 14:50:13 nappali volumio[760]: verbose: CURRENT POSITION 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::syncState stateService stop Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:13 nappali volumio[760]: info: No code Oct 13 14:50:13 nappali volumio[760]: info: CoreStateMachine::pushState Oct 13 14:50:13 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:13 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:13 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:13 nappali volumio[760]: info: ------------------------------ 88ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:13 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:50:15 nappali volumio[760]: info: CoreCommandRouter::volumioClearQueue Oct 13 14:50:15 nappali volumio[760]: info: CoreStateMachine::ClearQueue Oct 13 14:50:15 nappali volumio[760]: info: CoreStateMachine::stop Oct 13 14:50:15 nappali volumio[760]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:50:15 nappali volumio[760]: info: CorePlayQueue::clearPlayQueue Oct 13 14:50:15 nappali volumio[760]: info: CorePlayQueue::saveQueue Oct 13 14:50:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushState Oct 13 14:50:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output update for this device Oct 13 14:50:15 nappali volumio[760]: info: MRS: Pushing multiroomSync output Oct 13 14:50:15 nappali volumio[760]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:50:15 nappali volumio[760]: info: [GPIO_Control] *** musicStop *** Oct 13 14:50:15 nappali volumio[760]: info: [GPIO_Control] Delaying: 0ms Oct 13 14:50:15 nappali volumio[760]: info: [GPIO_Control] Turning GPIO 25 Off (musicStop) Oct 13 14:50:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 14:50:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 14:50:18 nappali volumio[760]: info: Discovery: Getting this device information Oct 13 14:50:18 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:50:18 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:18 nappali volumio[760]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 14:50:18 nappali volumio[760]: info: CoreCommandRouter::volumioGetState Oct 13 14:50:18 nappali volumio[760]: info: CorePlayQueue::getTrack 9 Oct 13 14:50:18 nappali volumio[760]: info: Listing playlists Oct 13 14:50:18 nappali volumio[760]: info: Listing playlists Oct 13 14:50:26 nappali volumio[760]: info: CoreCommandRouter::volumioAddQueueItems Oct 13 14:50:26 nappali volumio[760]: info: CoreStateMachine::addQueueItems Oct 13 14:50:26 nappali volumio[760]: info: CorePlayQueue::addQueueItems Oct 13 14:50:26 nappali volumio[760]: info: Preload queue cleared Oct 13 14:50:26 nappali volumio[760]: info: Adding Item to queue: music-library/NAS/NAS/DENON Audio Check HQCD (COCQ84695) Oct 13 14:50:26 nappali volumio[760]: info: Exploding uri music-library/NAS/NAS/DENON Audio Check HQCD (COCQ84695) in service mpd Oct 13 14:50:26 nappali volumio[760]: info: ALBUMART /albumart?cacheid=377&web=Unknown%20Artist/DENON%20Audio%20Check%20HQCD/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FDENON%20Audio%20Check%20HQCD%20(COCQ84695)%2FDENON%20Audio%20Check%20HQCD.cue&metadata=false Oct 13 14:50:26 nappali volumio[760]: info: URI /mnt/NAS/NAS/DENON Audio Check HQCD (COCQ84695)/DENON Audio Check HQCD.cue Oct 13 14:50:26 nappali volumio[760]: info: ALBUMART /albumart?cacheid=377&web=Unknown%20Artist/DENON%20Audio%20Check%20HQCD/extralarge&path=%2Fmnt%2FNAS%2FNAS%2FDENON%20Audio%20Check%20HQCD%20(COCQ84695)%2FDENON%20Audio%20Check%20HQCD.cue&metadata=false Oct 13 14:50:26 nappali volumio[760]: info: URI /mnt/NAS/NAS/DENON Audio Check HQCD (COCQ84695)/DENON Audio Check HQCD.cue Oct 13 14:50:26 nappali volumio[760]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 14:50:26 nappali volumio[760]: Error: Unable to resolve or reject the same promise twice Oct 13 14:50:26 nappali volumio[760]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 13 14:50:26 nappali volumio[760]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 13 14:50:26 nappali volumio[760]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 13 14:50:26 nappali volumio[760]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 13 14:50:26 nappali volumio[760]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 13 14:50:26 nappali volumio[760]: at Socket.emit (events.js:315:20) Oct 13 14:50:26 nappali volumio[760]: at addChunk (internal/streams/readable.js:309:12) Oct 13 14:50:26 nappali volumio[760]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 13 14:50:26 nappali volumio[760]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 13 14:50:26 nappali volumio[760]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 13 14:50:26 nappali volumio[760]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 14:50:27 nappali sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 14:49 Oct 13 14:50:27 nappali sudo[2394]: 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="e5e6090399db628f1586eb4951e3592507f5fd38" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="50b5825b3acc4c58b5033699bcdbbd219d6a0836" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Jan 2024 06:17:59 PM CET" VOLUMIO_VERSION="3.601" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="36b9da4b4411039910a860c6fa7331da"