-- Logs begin at Tue 2024-08-20 01:33:39 CEST, end at Tue 2024-08-20 02:25:41 CEST. -- Aug 20 02:24:08 volumio volumio[457]: info: Preload queue cleared Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::ClearQueue Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::stPlaybackTimer Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::serviceStop Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::serviceStop Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::stop Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::clearPlayQueue Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::addQueueItems Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::addQueueItems Aug 20 02:24:08 volumio volumio[457]: info: Preload queue cleared Aug 20 02:24:08 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Mod/1989.mod Aug 20 02:24:08 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Mod/1989.mod Aug 20 02:24:08 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Mod/kicstart.mod Aug 20 02:24:08 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Mod/kicstart.mod Aug 20 02:24:08 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Mod/ssforce.xm Aug 20 02:24:08 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Mod/ssforce.xm Aug 20 02:24:08 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Mod/STARTREK.MOD Aug 20 02:24:08 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Mod/STARTREK.MOD Aug 20 02:24:08 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Mod/Terminat.mod Aug 20 02:24:08 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Mod/Terminat.mod Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPlay Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::play index 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::play index undefined Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::startPlaybackTimer Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Mod/Terminat.mod Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand stop took 15 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand stop took 6 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:24:08 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand status took 3 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand clear took 3 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Mod/Terminat.mod" Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:24:08 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:24:08 volumio volumio[457]: error: updateQueue error: null Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:24:08 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand status took 3 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 3ms Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand add "INTERNAL/Mod/Terminat.mod" took 2 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand play Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 20 02:24:08 volumio volumio[457]: verbose: CURRENT POSITION 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState stateService stop Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: No code Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 16ms Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 19ms Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 14ms Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand play took 12 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:24:08 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 9ms Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand status took 2 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand status took 1 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:24:08 volumio volumio[457]: info: Aug 20 02:24:08 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand status took 2 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"terminator2","artist":null,"album":null,"uri":"INTERNAL/Mod/Terminat.mod","trackType":"mod"} Aug 20 02:24:08 volumio volumio[457]: verbose: CURRENT POSITION 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"terminator2","artist":null,"album":null,"uri":"INTERNAL/Mod/Terminat.mod","trackType":"mod"} Aug 20 02:24:08 volumio volumio[457]: verbose: CURRENT POSITION 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:24:08 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"terminator2","artist":null,"album":null,"uri":"INTERNAL/Mod/Terminat.mod","trackType":"mod"} Aug 20 02:24:08 volumio volumio[457]: verbose: CURRENT POSITION 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:24:08 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 18ms Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 27ms Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 27ms Aug 20 02:24:08 volumio volumio[457]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 20 02:24:08 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:24:08 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"terminator2","artist":null,"album":null,"uri":"INTERNAL/Mod/Terminat.mod","trackType":"mod"} Aug 20 02:24:08 volumio volumio[457]: verbose: CURRENT POSITION 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:24:08 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:24:08 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:24:08 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:24:08 volumio volumio[457]: info: ------------------------------ 48ms Aug 20 02:24:37 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 02:24:37 volumio volumio[457]: info: CURURI: music-library/INTERNAL/Midi Aug 20 02:24:37 volumio volumio[457]: info: Preload queue cleared Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/12daysXmas.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/bbdrag.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/beethove.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/chmuz682.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/csonatam.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/DAG_7.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/DEMO1.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/DESIRE.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/DRUM1.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/E2M1.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/EBB_TIDE.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/ECHOES1.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/et_advtg.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/furelise.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/georgia.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/GMBIGC20.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmbrand2.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmcanon.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmmoz550.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmmozart.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmmozpc2.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmnutrac.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmorient.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/GMPARTY.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmragtim.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmsurpri.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmtrepak.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/gmtrout.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/HEAVEN.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/Hey_Joe.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/HUMAN3GM.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/imperialsw.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/J_ParkG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/LAYLA.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/LstWrldG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/MIDIPC_1.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/MIDIPC_3.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/MIDIPC_4.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/minuet.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/moon_p.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/motionpic.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/nextgeneration.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/ORC2GM.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/ORC4GM.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/RaiderRG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/SNG_0012.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/southhampton.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/SpiritEG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/titanic.mid Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/TrekMedG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/TwisterG.MID Aug 20 02:24:37 volumio volumio[457]: info: Preloading song: music-library/INTERNAL/Midi/variatg.mid Aug 20 02:24:37 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/12daysXmas.mid in service mpd Aug 20 02:24:37 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2F12daysXmas.mid&metadata=false Aug 20 02:24:37 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/12daysXmas.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/bbdrag.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fbbdrag.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/bbdrag.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/beethove.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fbeethove.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/beethove.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/chmuz682.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fchmuz682.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/chmuz682.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/csonatam.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fcsonatam.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/csonatam.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/DAG_7.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FDAG_7.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/DAG_7.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/DEMO1.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FDEMO1.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/DEMO1.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/DESIRE.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FDESIRE.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/DESIRE.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/DRUM1.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FDRUM1.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/DRUM1.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/E2M1.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FE2M1.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/E2M1.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/EBB_TIDE.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FEBB_TIDE.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/EBB_TIDE.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/ECHOES1.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FECHOES1.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/ECHOES1.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/et_advtg.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fet_advtg.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/et_advtg.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/furelise.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Ffurelise.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/furelise.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/georgia.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgeorgia.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/georgia.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/GMBIGC20.MID in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FGMBIGC20.MID&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/GMBIGC20.MID Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmbrand2.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmbrand2.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmbrand2.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmcanon.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmcanon.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmcanon.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmmoz550.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmmoz550.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmmoz550.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmmozart.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmmozart.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmmozart.mid Aug 20 02:24:38 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmmozpc2.mid in service mpd Aug 20 02:24:38 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmmozpc2.mid&metadata=false Aug 20 02:24:38 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmmozpc2.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmnutrac.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmnutrac.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmnutrac.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmorient.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmorient.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmorient.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/GMPARTY.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FGMPARTY.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/GMPARTY.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmragtim.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmragtim.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmragtim.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmsurpri.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmsurpri.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmsurpri.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmtrepak.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmtrepak.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmtrepak.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/gmtrout.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fgmtrout.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/gmtrout.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/HEAVEN.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FHEAVEN.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/HEAVEN.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/Hey_Joe.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FHey_Joe.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/Hey_Joe.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/HUMAN3GM.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FHUMAN3GM.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/HUMAN3GM.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/imperialsw.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fimperialsw.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/imperialsw.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/J_ParkG.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FJ_ParkG.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/J_ParkG.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/LAYLA.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FLAYLA.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/LAYLA.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/LstWrldG.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FLstWrldG.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/LstWrldG.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/MIDIPC_1.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FMIDIPC_1.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/MIDIPC_1.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/MIDIPC_3.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FMIDIPC_3.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/MIDIPC_3.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/MIDIPC_4.MID in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FMIDIPC_4.MID&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/MIDIPC_4.MID Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/minuet.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fminuet.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/minuet.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/moon_p.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fmoon_p.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/moon_p.mid Aug 20 02:24:39 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/motionpic.mid in service mpd Aug 20 02:24:39 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fmotionpic.mid&metadata=false Aug 20 02:24:39 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/motionpic.mid Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/nextgeneration.mid in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fnextgeneration.mid&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/nextgeneration.mid Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/ORC2GM.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FORC2GM.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/ORC2GM.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/ORC4GM.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FORC4GM.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/ORC4GM.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/RaiderRG.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FRaiderRG.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/RaiderRG.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/SNG_0012.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FSNG_0012.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/SNG_0012.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/southhampton.mid in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fsouthhampton.mid&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/southhampton.mid Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/SpiritEG.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FSpiritEG.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/SpiritEG.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/titanic.mid in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Ftitanic.mid&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/titanic.mid Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/TrekMedG.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FTrekMedG.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/TrekMedG.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/TwisterG.MID in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2FTwisterG.MID&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/TwisterG.MID Aug 20 02:24:40 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/Midi/variatg.mid in service mpd Aug 20 02:24:40 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMidi%2Fvariatg.mid&metadata=false Aug 20 02:24:40 volumio volumio[457]: info: URI /mnt/INTERNAL/Midi/variatg.mid Aug 20 02:25:04 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::ClearQueue Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::stPlaybackTimer Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::serviceStop Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 4 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::serviceStop Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::stop Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::clearPlayQueue Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::addQueueItems Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::addQueueItems Aug 20 02:25:04 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/12daysXmas.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/12daysXmas.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/bbdrag.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/bbdrag.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/beethove.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/beethove.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/chmuz682.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/chmuz682.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/csonatam.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/csonatam.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/DAG_7.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/DAG_7.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/DEMO1.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/DEMO1.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/DESIRE.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/DESIRE.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/DRUM1.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/DRUM1.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/E2M1.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/E2M1.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/EBB_TIDE.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/EBB_TIDE.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/ECHOES1.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/ECHOES1.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/et_advtg.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/et_advtg.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/furelise.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/furelise.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/georgia.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/georgia.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/GMBIGC20.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/GMBIGC20.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmbrand2.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmbrand2.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmcanon.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmcanon.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmmoz550.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmmoz550.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmmozart.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmmozart.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmmozpc2.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmmozpc2.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmnutrac.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmnutrac.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmorient.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmorient.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/GMPARTY.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/GMPARTY.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmragtim.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmragtim.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmsurpri.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmsurpri.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmtrepak.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmtrepak.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/gmtrout.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/gmtrout.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/HEAVEN.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/HEAVEN.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/Hey_Joe.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/Hey_Joe.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/HUMAN3GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/HUMAN3GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/imperialsw.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/imperialsw.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/J_ParkG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/J_ParkG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/LAYLA.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/LAYLA.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/LstWrldG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/LstWrldG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/MIDIPC_1.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/MIDIPC_1.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/MIDIPC_3.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/MIDIPC_3.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/MIDIPC_4.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/MIDIPC_4.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/minuet.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/minuet.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/moon_p.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/moon_p.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/motionpic.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/motionpic.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/nextgeneration.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/nextgeneration.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/ORC2GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/ORC2GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/ORC4GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/ORC4GM.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/RaiderRG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/RaiderRG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/SNG_0012.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/SNG_0012.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/southhampton.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/southhampton.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/SpiritEG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/SpiritEG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/titanic.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/titanic.mid Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/TrekMedG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/TrekMedG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/TwisterG.MID Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/TwisterG.MID Aug 20 02:25:04 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/Midi/variatg.mid Aug 20 02:25:04 volumio volumio[457]: info: Using cached record of: music-library/INTERNAL/Midi/variatg.mid Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPlay Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::play index 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::play index undefined Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::startPlaybackTimer Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Midi/titanic.mid Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand stop took 19 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand stop took 3 milliseconds Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:04 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand clear took 0 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Midi/titanic.mid" Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 20 02:25:04 volumio volumio[457]: verbose: CURRENT POSITION 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState stateService stop Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: No code Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 9ms Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:04 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:04 volumio volumio[457]: error: updateQueue error: null Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:04 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 10ms Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand add "INTERNAL/Midi/titanic.mid" took 10 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 1ms Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand play Aug 20 02:25:04 volumio volumio[457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 12ms Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:04 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 5ms Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand play took 4 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 5ms Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 2 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 1 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:04 volumio volumio[457]: info: Aug 20 02:25:04 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 1 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"titanic.mid","artist":null,"album":null,"uri":"INTERNAL/Midi/titanic.mid","trackType":"mid"} Aug 20 02:25:04 volumio volumio[457]: verbose: CURRENT POSITION 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"titanic.mid","artist":null,"album":null,"uri":"INTERNAL/Midi/titanic.mid","trackType":"mid"} Aug 20 02:25:04 volumio volumio[457]: verbose: CURRENT POSITION 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:04 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 7ms Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 9ms Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 20 02:25:04 volumio volumio[457]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:04 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"titanic.mid","artist":null,"album":null,"uri":"INTERNAL/Midi/titanic.mid","trackType":"mid"} Aug 20 02:25:04 volumio volumio[457]: verbose: CURRENT POSITION 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:04 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"titanic.mid","artist":null,"album":null,"uri":"INTERNAL/Midi/titanic.mid","trackType":"mid"} Aug 20 02:25:04 volumio volumio[457]: verbose: CURRENT POSITION 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:04 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:04 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:04 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 32ms Aug 20 02:25:04 volumio volumio[457]: info: ------------------------------ 32ms Aug 20 02:25:18 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 02:25:18 volumio volumio[457]: info: CURURI: music-library/INTERNAL/SID Aug 20 02:25:18 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:22 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 02:25:22 volumio volumio[457]: info: CURURI: music-library/INTERNAL/SID/my Aug 20 02:25:22 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:33 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::ClearQueue Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::stPlaybackTimer Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::serviceStop Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 48 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::serviceStop Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::stop Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::clearPlayQueue Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::addQueueItems Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::addQueueItems Aug 20 02:25:33 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:33 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/SID/my/Crazy.sid Aug 20 02:25:33 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/SID/my/Crazy.sid in service mpd Aug 20 02:25:33 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=Joe%20Barwick%20(Stainless%20Steel)/Crazy/extralarge&path=%2Fmnt%2FINTERNAL%2FSID%2Fmy%2FCrazy.sid&metadata=false Aug 20 02:25:33 volumio volumio[457]: info: URI /mnt/INTERNAL/SID/my/Crazy.sid Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand stop took 24 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPlay Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::play index 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::play index undefined Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::startPlaybackTimer Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/SID/my/Crazy.sid Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 5 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand stop took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:33 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand clear took 1 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/SID/my/Crazy.sid" Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: 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":"titanic.mid","artist":null,"album":null,"uri":"INTERNAL/Midi/titanic.mid","trackType":"mid"} Aug 20 02:25:33 volumio volumio[457]: verbose: CURRENT POSITION 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState stateService stop Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: No code Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 13ms Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 8ms Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:33 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:33 volumio volumio[457]: error: updateQueue error: null Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:33 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 9ms Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand add "INTERNAL/SID/my/Crazy.sid" took 8 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 2ms Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand play Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces system playlist update Aug 20 02:25:33 volumio volumio[457]: info: Ignoring MPD Status Update Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 6ms Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand play took 5 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 5ms Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: Aug 20 02:25:33 volumio volumio[457]: ---------------------------- MPD announces state update: player Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::getState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand status Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand status took 1 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseState Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy","artist":"Joe Barwick (Stainless Steel)","album":"Crazy","uri":"INTERNAL/SID/my/Crazy.sid/tune_001.sid","trackType":"sid"} Aug 20 02:25:33 volumio volumio[457]: verbose: CURRENT POSITION 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus stop Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy","artist":"Joe Barwick (Stainless Steel)","album":"Crazy","uri":"INTERNAL/SID/my/Crazy.sid/tune_001.sid","trackType":"sid"} Aug 20 02:25:33 volumio volumio[457]: verbose: CURRENT POSITION 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:33 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy","artist":"Joe Barwick (Stainless Steel)","album":"Crazy","uri":"INTERNAL/SID/my/Crazy.sid/tune_001.sid","trackType":"sid"} Aug 20 02:25:33 volumio volumio[457]: verbose: CURRENT POSITION 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:33 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 8ms Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 13ms Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 14ms Aug 20 02:25:33 volumio volumio[457]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 20 02:25:33 volumio volumio[457]: verbose: ControllerMpd::parseTrackInfo Aug 20 02:25:33 volumio volumio[457]: info: ControllerMpd::pushState Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::servicePushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy","artist":"Joe Barwick (Stainless Steel)","album":"Crazy","uri":"INTERNAL/SID/my/Crazy.sid/tune_001.sid","trackType":"sid"} Aug 20 02:25:33 volumio volumio[457]: verbose: CURRENT POSITION 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState stateService play Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::syncState currentStatus play Aug 20 02:25:33 volumio volumio[457]: info: Received an update from plugin. extracting info from payload Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:33 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:33 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:33 volumio volumio[457]: info: ------------------------------ 24ms Aug 20 02:25:41 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:41 volumio volumio[457]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::ClearQueue Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::stop Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::stPlaybackTimer Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::updateTrackBlock Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::getTrackBlock Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::pushState Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:41 volumio volumio[457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 02:25:41 volumio volumio[457]: info: CoreCommandRouter::volumioPushState Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::serviceStop Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::getTrack 0 Aug 20 02:25:41 volumio volumio[457]: info: CoreCommandRouter::serviceStop Aug 20 02:25:41 volumio volumio[457]: info: ControllerMpd::stop Aug 20 02:25:41 volumio volumio[457]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::clearPlayQueue Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::saveQueue Aug 20 02:25:41 volumio volumio[457]: info: CoreCommandRouter::volumioPushQueue Aug 20 02:25:41 volumio volumio[457]: info: CoreStateMachine::addQueueItems Aug 20 02:25:41 volumio volumio[457]: info: CorePlayQueue::addQueueItems Aug 20 02:25:41 volumio volumio[457]: info: Preload queue cleared Aug 20 02:25:41 volumio volumio[457]: info: Adding Item to queue: music-library/INTERNAL/SID/my/Last_V8.sid Aug 20 02:25:41 volumio volumio[457]: info: Exploding uri music-library/INTERNAL/SID/my/Last_V8.sid in service mpd Aug 20 02:25:41 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=Rob%20Hubbard/The%20Last%20V8/extralarge&path=%2Fmnt%2FINTERNAL%2FSID%2Fmy%2FLast_V8.sid&metadata=false Aug 20 02:25:41 volumio volumio[457]: info: URI /mnt/INTERNAL/SID/my/Last_V8.sid Aug 20 02:25:41 volumio volumio[457]: info: ALBUMART /albumart?cacheid=166&web=Rob%20Hubbard/The%20Last%20V8/extralarge&path=%2Fmnt%2FINTERNAL%2FSID%2Fmy%2FLast_V8.sid&metadata=false Aug 20 02:25:41 volumio volumio[457]: info: URI /mnt/INTERNAL/SID/my/Last_V8.sid Aug 20 02:25:41 volumio volumio[457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 02:25:41 volumio volumio[457]: Error: Unable to resolve or reject the same promise twice Aug 20 02:25:41 volumio volumio[457]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 20 02:25:41 volumio volumio[457]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 20 02:25:41 volumio volumio[457]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 20 02:25:41 volumio volumio[457]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 20 02:25:41 volumio volumio[457]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 20 02:25:41 volumio volumio[457]: at Socket.emit (events.js:400:28) Aug 20 02:25:41 volumio volumio[457]: at addChunk (internal/streams/readable.js:293:12) Aug 20 02:25:41 volumio volumio[457]: at readableAddChunk (internal/streams/readable.js:263:11) Aug 20 02:25:41 volumio volumio[457]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 20 02:25:41 volumio volumio[457]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 20 02:25:41 volumio volumio[457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 02:25:41 volumio sudo[3437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 02:24 Aug 20 02:25:41 volumio sudo[3437]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"