-- Logs begin at Sat 2024-08-17 14:53:33 UTC, end at Sat 2024-08-17 15:31:47 UTC. -- Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: CURURI: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track02.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track03.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track04.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track05.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track06.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track07.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track08.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track09.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track10.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track11.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track12.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Preloading song: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track13.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack01.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track02.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack02.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track02.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track03.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack03.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track03.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track04.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack04.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track04.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track05.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack05.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track05.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track06.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack06.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track06.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track07.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack07.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track07.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track08.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack08.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track08.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track09.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack09.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track09.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track10.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack10.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track10.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track11.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack11.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track11.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track12.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack12.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track12.flac Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track13.flac in service mpd Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FTrack13.flac&metadata=false Aug 17 15:30:03 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track13.flac Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 95 Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Prefetching next song Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: DOING PREFETCH IN MPD Aug 17 15:30:08 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/WAV/Cool Band CD2/11 Track11.wav" Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Aug 17 15:30:08 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: sendMpdCommand add "NAS/Nas/WAV/Cool Band CD2/11 Track11.wav" took 1 milliseconds Aug 17 15:30:08 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: sendMpdCommand consume 1 took 0 milliseconds Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Aug 17 15:30:08 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Aug 17 15:30:08 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:30:08 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioAddQueueItems Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CoreStateMachine::addQueueItems Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CorePlayQueue::addQueueItems Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: Adding Item to queue: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue in service mpd Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushQueue Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CorePlayQueue::saveQueue Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CoreStateMachine::updateTrackBlock Aug 17 15:30:12 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrackBlock Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::startPlaybackTimer Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Discovery: Getting this device information Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Aug 17 15:30:13 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 3ms Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 2 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 2ms Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Track11.wav","artist":null,"album":null,"uri":"NAS/Nas/WAV/Cool Band CD2/11 Track11.wav","trackType":"wav"} Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Track11.wav","artist":null,"album":null,"uri":"NAS/Nas/WAV/Cool Band CD2/11 Track11.wav","trackType":"wav"} Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 13ms Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 13ms Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Track11.wav","artist":null,"album":null,"uri":"NAS/Nas/WAV/Cool Band CD2/11 Track11.wav","trackType":"wav"} Aug 17 15:30:13 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:30:13 volumio-thinkpad volumio[834]: info: ------------------------------ 16ms Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioAddQueueItems Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreStateMachine::addQueueItems Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CorePlayQueue::addQueueItems Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: Adding Item to queue: music-library/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium in service mpd Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F01%20Supremacy.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F02%20Panic%20Station.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/02 Panic Station.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F03%20Resistance.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/03 Resistance.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F04%20Hysteria.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/04 Hysteria.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F05%20Animals.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/05 Animals.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F06%20Knights%20Of%20Cydonia.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/06 Knights Of Cydonia.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F07%20Explorers.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/07 Explorers.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F08%20Follow%20Me.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/08 Follow Me.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F09%20Madness.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/09 Madness.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F10%20Guiding%20Light.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/10 Guiding Light.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F11%20Supermassive%20Black%20Hole.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/11 Supermassive Black Hole.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F12%20Uprising.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/12 Uprising.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Muse/Live%20At%20Rome%20Olympic%20Stadium/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FMuse%20-%20Live%20at%20Rome%20Olympic%20Stadium%2F13%20Starlight.flac&metadata=false Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/13 Starlight.flac Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushQueue Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CorePlayQueue::saveQueue Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreStateMachine::updateTrackBlock Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrackBlock Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: Discovery: Getting this device information Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:30:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 15:30:50 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:30:50 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPlay Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::play index 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::stPlaybackTimer Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::updateTrackBlock Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrackBlock Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::serviceStop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 96 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::serviceStop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand stop took 2 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::play index undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::startPlaybackTimer Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand stop took 1 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 0 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 0 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand clear took 0 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand load "NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue" Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: 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":"11 Track11.wav","artist":null,"album":null,"uri":"NAS/Nas/WAV/Cool Band CD2/11 Track11.wav","trackType":"wav"} Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: No code Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 10ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 10ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 9ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: error: updateQueue error: null Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 8ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand load "NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue" took 12 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 6ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 6ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand play took 2 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 2 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 4 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 3 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 7ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Aug 17 15:31:10 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 25ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 25ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: ------------------------------ 24ms Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 19 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 19 milliseconds Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:10 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 760 milliseconds Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 760 milliseconds Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:11 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: ------------------------------ 791ms Aug 17 15:31:11 volumio-thinkpad volumio[834]: info: ------------------------------ 790ms Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: Discovery: Getting this device information Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:13 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioRemoveQueueItem Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::removeQueueItem Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::stPlaybackTimer Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::updateTrackBlock Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrackBlock Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::serviceStop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::serviceStop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::removeQueueItem 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::saveQueue Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushQueue Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: Aug 17 15:31:19 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand stop took 28 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: Aug 17 15:31:19 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: Aug 17 15:31:19 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 2 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 2 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 2 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: 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":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: No code Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: 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":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: No code Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ------------------------------ 16ms Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ------------------------------ 15ms Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: 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":"Track01.flac","artist":"Various Artists","album":"Linkin Park Meteora","uri":"NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Track01.flac","trackType":"flac"} Aug 17 15:31:19 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: No code Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:19 volumio-thinkpad volumio[834]: info: ------------------------------ 19ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPlay Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::play index 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::stop Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::play index undefined Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::startPlaybackTimer Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand stop took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand clear took 0 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac" Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand add "NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac" took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces system playlist update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Ignoring MPD Status Update Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 1ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand play took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 0ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 0 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Aug 17 15:31:21 volumio-thinkpad volumio[834]: ---------------------------- MPD announces state update: player Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::getState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand status Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 1 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand status took 0 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseState Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus stop Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 8ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 14ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: ControllerMpd::parseTrackInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"785 Kbps","isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ControllerMpd::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::servicePushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"807 Kbps","isStreaming":false,"title":"Supremacy","artist":"Muse","album":"Live At Rome Olympic Stadium","uri":"NAS/Nas/Flac/Muse - Live at Rome Olympic Stadium/01 Supremacy.flac","trackType":"flac"} Aug 17 15:31:21 volumio-thinkpad volumio[834]: verbose: CURRENT POSITION 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState stateService play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::syncState currentStatus play Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: Received an update from plugin. extracting info from payload Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreStateMachine::pushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioPushState Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 29ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 28ms Aug 17 15:31:21 volumio-thinkpad volumio[834]: info: ------------------------------ 28ms Aug 17 15:31:23 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:31:23 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:27 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 15:31:27 volumio-thinkpad volumio[834]: info: CURURI: music-library/NAS Aug 17 15:31:27 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:31:28 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 15:31:28 volumio-thinkpad volumio[834]: info: CURURI: music-library/NAS/Nas Aug 17 15:31:28 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:31:29 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 15:31:29 volumio-thinkpad volumio[834]: info: CURURI: music-library/NAS/Nas/Flac Aug 17 15:31:29 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: Discovery: Getting this device information Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioGetState Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: CorePlayQueue::getTrack 714 Aug 17 15:31:43 volumio-thinkpad volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: CoreCommandRouter::volumioAddQueueItems Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: CoreStateMachine::addQueueItems Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: CorePlayQueue::addQueueItems Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: Preload queue cleared Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: Adding Item to queue: music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: Exploding uri music-library/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k in service mpd Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Various%20Artists/Linkin%20Park%20Meteora/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FLinkin%20Park%20Meteora.cue&metadata=false Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: ALBUMART /albumart?cacheid=272&web=Various%20Artists/Linkin%20Park%20Meteora/extralarge&path=%2Fmnt%2FNAS%2FNas%2FFlac%2FLinkin-Park-Meteora-CDRip-g4m3fre4k%2FLinkin%20Park%20Meteora.cue&metadata=false Aug 17 15:31:47 volumio-thinkpad volumio[834]: info: URI /mnt/NAS/Nas/Flac/Linkin-Park-Meteora-CDRip-g4m3fre4k/Linkin Park Meteora.cue Aug 17 15:31:47 volumio-thinkpad volumio[834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 15:31:47 volumio-thinkpad volumio[834]: Error: Unable to resolve or reject the same promise twice Aug 17 15:31:47 volumio-thinkpad volumio[834]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 17 15:31:47 volumio-thinkpad volumio[834]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at Socket.emit (events.js:400:28) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at addChunk (internal/streams/readable.js:293:12) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at readableAddChunk (internal/streams/readable.js:263:11) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 17 15:31:47 volumio-thinkpad volumio[834]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 17 15:31:47 volumio-thinkpad volumio[834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 15:31:47 volumio-thinkpad sudo[5848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 15:30 Aug 17 15:31:47 volumio-thinkpad sudo[5848]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 04:30:21 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2a4e15ea1ce484f8b0c1c7a110d46b73"