Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:22 volumio volumio[1054]: info: Prefetching next song Oct 17 12:03:22 volumio volumio[1054]: info: DOING PREFETCH IN MPD Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand add "NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3" Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand add "NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3" took 4 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: options Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 8ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand consume 1 took 5 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 5 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 5ms Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 3ms Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: options Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: options Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251970,"duration":253,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bliss","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Bliss.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 74ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 65 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 62 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251970,"duration":253,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bliss","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Bliss.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251970,"duration":253,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bliss","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Bliss.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 162ms Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 159ms Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: player Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: player Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces system playlist update Oct 17 12:03:22 volumio volumio[1054]: info: Ignoring MPD Status Update Oct 17 12:03:22 volumio volumio[1054]: info: Oct 17 12:03:22 volumio volumio[1054]: ---------------------------- MPD announces state update: player Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 8ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 7 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 9ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 7 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 8ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand status took 7 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 48ms Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 17 12:03:22 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:22 volumio volumio[1054]: verbose: CURRENT POSITION 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:22 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:22 volumio volumio[1054]: info: CorePlayQueue::getTrack 3 Oct 17 12:03:22 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 112ms Oct 17 12:03:22 volumio volumio[1054]: info: ------------------------------ 111ms Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:26 volumio volumio[1054]: info: CoreStateMachine::startPlaybackTimer Oct 17 12:03:26 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:27 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:27 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:27 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:27 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:27 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:39 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: Oct 17 12:03:39 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:39 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:39 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:42 volumio volumio[1054]: info: sendMpdCommand status took 2428 milliseconds Oct 17 12:03:42 volumio volumio[1054]: info: sendMpdCommand status took 2428 milliseconds Oct 17 12:03:42 volumio volumio[1054]: info: sendMpdCommand status took 2427 milliseconds Oct 17 12:03:42 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:42 volumio volumio[1054]: info: Command Router : Notfying DB Updatetrue Oct 17 12:03:42 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:42 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:42 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:42 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:42 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:43 volumio volumio[1054]: info: sendMpdCommand status took 3914 milliseconds Oct 17 12:03:43 volumio volumio[1054]: info: sendMpdCommand status took 3914 milliseconds Oct 17 12:03:43 volumio volumio[1054]: info: sendMpdCommand status took 3914 milliseconds Oct 17 12:03:43 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:43 volumio volumio[1054]: info: Command Router : Notfying DB Updatetrue Oct 17 12:03:43 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:43 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:43 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:43 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:43 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand status took 5401 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand status took 5401 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand status took 5401 milliseconds Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:45 volumio volumio[1054]: info: Command Router : Notfying DB Updatetrue Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 2974 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 2974 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 1513 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 1513 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 17 12:03:45 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16997,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16997,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17250,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17250,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17629,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17629,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:45 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:45 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:45 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:45 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5668ms Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5668ms Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5665ms Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5665ms Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5662ms Oct 17 12:03:45 volumio volumio[1054]: info: ------------------------------ 5662ms Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:45 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:47 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 12:03:47 volumio volumio[1054]: info: CURURI: music-library/NAS/Blackhole Oct 17 12:03:47 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:03:51 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: MPD Database updated - AlbumList cache refreshed Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: database Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: Oct 17 12:03:51 volumio volumio[1054]: ---------------------------- MPD announces state update: update Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:51 volumio volumio[1054]: info: ControllerMpd::getState Oct 17 12:03:51 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand status Oct 17 12:03:53 volumio volumio[1054]: info: sendMpdCommand status took 2420 milliseconds Oct 17 12:03:53 volumio volumio[1054]: info: sendMpdCommand status took 2419 milliseconds Oct 17 12:03:53 volumio volumio[1054]: info: sendMpdCommand status took 2419 milliseconds Oct 17 12:03:53 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:53 volumio volumio[1054]: info: Command Router : Notfying DB Updatefalse Oct 17 12:03:53 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:53 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:53 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:53 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:53 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:54 volumio volumio[1054]: info: sendMpdCommand status took 3794 milliseconds Oct 17 12:03:54 volumio volumio[1054]: info: sendMpdCommand status took 3794 milliseconds Oct 17 12:03:54 volumio volumio[1054]: info: sendMpdCommand status took 3793 milliseconds Oct 17 12:03:54 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:54 volumio volumio[1054]: info: Command Router : Notfying DB Updatefalse Oct 17 12:03:54 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:54 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:54 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:54 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:54 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand status took 5161 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand status took 5161 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand status took 5161 milliseconds Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:56 volumio volumio[1054]: info: Command Router : Notfying DB Updatefalse Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::Close All Modals sent Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseState Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 2751 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 2752 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 1393 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 1392 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 17 12:03:56 volumio volumio[1054]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: verbose: ControllerMpd::parseTrackInfo Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28392,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28392,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28750,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28750,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29003,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ControllerMpd::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::servicePushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29003,"duration":296,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sing for Absolution","artist":"Muse","album":"Greatest Hits","uri":"NAS/Blackhole/Muse/Greatest Hits (2012)/Muse - Sing For Absolution.mp3","trackType":"mp3"} Oct 17 12:03:56 volumio volumio[1054]: verbose: CURRENT POSITION 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState stateService play Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::syncState currentStatus play Oct 17 12:03:56 volumio volumio[1054]: info: Received an update from plugin. extracting info from payload Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:03:56 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:03:56 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5394ms Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5393ms Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5390ms Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5389ms Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5387ms Oct 17 12:03:56 volumio volumio[1054]: info: ------------------------------ 5387ms Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:03:56 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:04:09 volumio go-librespot[1356]: time="2026-10-17T12:04:09+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:list:play-history:v1/resume-point-revision/de716ee2-ccc4-4ecf-8de4-3c3184c1c8f9" Oct 17 12:04:09 volumio go-librespot[1356]: time="2026-10-17T12:04:09+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 304" Oct 17 12:04:12 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 12:04:12 volumio volumio[1054]: info: CURURI: music-library/NAS/Blackhole/Giant Sky Oct 17 12:04:12 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - broken stone clip.mp4 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - Intro.mp4 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 01 The Further We Go The Deeper It Gets Pt. 1-6.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 02 Broken Stone.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 03 Interlude (I Don't Know What You Think Of Me But It's Wrong).mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 04 No Cancelling This.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 05 Out Of Swords.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 06 The Further We Go The Deeper It Gets Pt. 7.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 07 Breaking Patterns.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - broken stone clip.mp4 in service mpd Oct 17 12:04:12 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=//extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%20broken%20stone%20clip.mp4&metadata=false Oct 17 12:04:12 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - broken stone clip.mp4 Oct 17 12:04:12 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - Intro.mp4 in service mpd Oct 17 12:04:12 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=//extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%20Intro.mp4&metadata=false Oct 17 12:04:12 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - Intro.mp4 Oct 17 12:04:12 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 01 The Further We Go The Deeper It Gets Pt. 1-6.mp3 in service mpd Oct 17 12:04:12 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2001%20The%20Further%20We%20Go%20The%20Deeper%20It%20Gets%20Pt.%201-6.mp3&metadata=false Oct 17 12:04:12 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 01 The Further We Go The Deeper It Gets Pt. 1-6.mp3 Oct 17 12:04:12 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 02 Broken Stone.mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 03 Interlude (I Don't Know What You Think Of Me But It's Wrong).mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 04 No Cancelling This.mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 05 Out Of Swords.mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 06 The Further We Go The Deeper It Gets Pt. 7.mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 07 Breaking Patterns.mp3 in service mpd Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2002%20Broken%20Stone.mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 02 Broken Stone.mp3 Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2003%20Interlude%20(I%20Don't%20Know%20What%20You%20Think%20Of%20Me%20But%20It's%20Wrong).mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 03 Interlude (I Don't Know What You Think Of Me But It's Wrong).mp3 Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2004%20No%20Cancelling%20This.mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 04 No Cancelling This.mp3 Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2005%20Out%20Of%20Swords.mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 05 Out Of Swords.mp3 Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2006%20The%20Further%20We%20Go%20The%20Deeper%20It%20Gets%20Pt.%207.mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 06 The Further We Go The Deeper It Gets Pt. 7.mp3 Oct 17 12:04:13 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=Giant%20Sky/Giant%20Sky/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20-%20Giant%20Sky%20-%2007%20Breaking%20Patterns.mp3&metadata=false Oct 17 12:04:13 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky - Giant Sky - 07 Breaking Patterns.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 12:04:19 volumio volumio[1054]: info: CURURI: music-library/NAS/Blackhole/Giant Sky/Giant Sky III Oct 17 12:04:19 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 01 Mundane Morning.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 02 Nothing To Forgive Nothing To Forget.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 04 All The Rage.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 05 Reveries Of Gold.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 06 Fleeting Glimpse.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 07 Sun World.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Preloading song: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 08 Lifting Apples.mp3 Oct 17 12:04:19 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 01 Mundane Morning.mp3 in service mpd Oct 17 12:04:19 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2001%20Mundane%20Morning.mp3&metadata=false Oct 17 12:04:19 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 01 Mundane Morning.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 02 Nothing To Forgive Nothing To Forget.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2002%20Nothing%20To%20Forgive%20Nothing%20To%20Forget.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 02 Nothing To Forgive Nothing To Forget.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2003%20Limerence.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 04 All The Rage.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2004%20All%20The%20Rage.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 04 All The Rage.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 05 Reveries Of Gold.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2005%20Reveries%20Of%20Gold.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 05 Reveries Of Gold.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 06 Fleeting Glimpse.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2006%20Fleeting%20Glimpse.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 06 Fleeting Glimpse.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 07 Sun World.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2007%20Sun%20World.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 07 Sun World.mp3 Oct 17 12:04:20 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 08 Lifting Apples.mp3 in service mpd Oct 17 12:04:20 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%2008%20Lifting%20Apples.mp3&metadata=false Oct 17 12:04:20 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 08 Lifting Apples.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::ClearQueue Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::stop Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::stPlaybackTimer Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::updateTrackBlock Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrackBlock Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::pushState Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::volumioPushState Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::serviceStop Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrack 4 Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::serviceStop Oct 17 12:04:23 volumio volumio[1054]: info: ControllerMpd::stop Oct 17 12:04:23 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::clearPlayQueue Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::saveQueue Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::volumioPushQueue Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::addQueueItems Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::addQueueItems Oct 17 12:04:23 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 01 Mundane Morning.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 01 Mundane Morning.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 02 Nothing To Forgive Nothing To Forget.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 02 Nothing To Forgive Nothing To Forget.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::volumioPushQueue Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::saveQueue Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::updateTrackBlock Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrackBlock Oct 17 12:04:23 volumio volumio[1054]: info: CoreCommandRouter::volumioPlay Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::play index 2 Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::addQueueItems Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::addQueueItems Oct 17 12:04:23 volumio volumio[1054]: info: Preload queue cleared Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 04 All The Rage.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 04 All The Rage.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 05 Reveries Of Gold.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 05 Reveries Of Gold.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 06 Fleeting Glimpse.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 06 Fleeting Glimpse.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 07 Sun World.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 07 Sun World.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 08 Lifting Apples.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Using cached record of: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 08 Lifting Apples.mp3 Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - FLAC.zip Oct 17 12:04:23 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - FLAC.zip in service mpd Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - MP3 320Mbs.zip Oct 17 12:04:23 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - MP3 320Mbs.zip in service mpd Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - Ogg.zip Oct 17 12:04:23 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - Ogg.zip in service mpd Oct 17 12:04:23 volumio volumio[1054]: info: Adding Item to queue: music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III.zip Oct 17 12:04:23 volumio volumio[1054]: info: Exploding uri music-library/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III.zip in service mpd Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::stop Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::play index undefined Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrack 2 Oct 17 12:04:23 volumio volumio[1054]: info: CoreStateMachine::startPlaybackTimer Oct 17 12:04:23 volumio volumio[1054]: info: CorePlayQueue::getTrack 2 Oct 17 12:04:23 volumio volumio[1054]: verbose: ControllerMpd::clearAddPlayTracks NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - 03 Limerence.mp3 Oct 17 12:04:23 volumio volumio[1054]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 12:04:23 volumio volumio[1054]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Oct 17 12:04:23 volumio volumio[1054]: info: Oct 17 12:04:23 volumio volumio[1054]: ---------------------------- MPD announces state update: player Oct 17 12:04:23 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%20FLAC.zip&metadata=false Oct 17 12:04:23 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - FLAC.zip Oct 17 12:04:23 volumio volumio[1054]: info: ALBUMART /albumart?cacheid=981&web=GIANTSKY/Giant%20Sky%20III/extralarge&path=%2Fmnt%2FNAS%2FBlackhole%2FGiant%20Sky%2FGiant%20Sky%20III%2FGIANTSKY%20-%20Giant%20Sky%20III%20-%20FLAC.zip&metadata=false Oct 17 12:04:23 volumio volumio[1054]: info: URI /mnt/NAS/Blackhole/Giant Sky/Giant Sky III/GIANTSKY - Giant Sky III - FLAC.zip Oct 17 12:04:23 volumio volumio[1054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:04:23 volumio volumio[1054]: Error: Unable to resolve or reject the same promise twice Oct 17 12:04:23 volumio volumio[1054]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 17 12:04:23 volumio volumio[1054]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 17 12:04:23 volumio volumio[1054]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 17 12:04:23 volumio volumio[1054]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 17 12:04:23 volumio volumio[1054]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 17 12:04:23 volumio volumio[1054]: at Socket.emit (node:events:514:28) Oct 17 12:04:23 volumio volumio[1054]: at addChunk (node:internal/streams/readable:343:12) Oct 17 12:04:23 volumio volumio[1054]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 17 12:04:23 volumio volumio[1054]: at Readable.push (node:internal/streams/readable:253:10) Oct 17 12:04:23 volumio volumio[1054]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 17 12:04:23 volumio volumio[1054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:04:24 volumio sudo[3327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:03' Oct 17 12:04:24 volumio sudo[3327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"