-- Logs begin at Tue 2025-04-08 18:43:22 UTC, end at Sat 2025-04-12 23:49:07 UTC. -- Apr 12 23:48:08 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 7 Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::serviceStop Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 7 Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::serviceStop Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::stop Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:08 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:08 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/Новая папка Apr 12 23:48:08 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/Новая папка Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::play index 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/Новая папка/010. Ace Of Base - Never Gonna Say I'm Sorry.mp3 Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:08 volumio volumio[892]: info: Apr 12 23:48:08 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand stop took 29 milliseconds Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand stop took 10 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:08 volumio volumio[892]: info: Apr 12 23:48:08 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:08 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand status took 3 milliseconds Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand clear took 2 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/Новая папка/010. Ace Of Base - Never Gonna Say I'm Sorry.mp3" Apr 12 23:48:08 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:08 volumio volumio[892]: info: ------------------------------ 5ms Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:08 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:08 volumio volumio[892]: info: ------------------------------ 12ms Apr 12 23:48:08 volumio volumio[892]: info: Apr 12 23:48:08 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:08 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/Новая папка/010. Ace Of Base - Never Gonna Say I'm Sorry.mp3" took 9 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:08 volumio volumio[892]: info: ------------------------------ 7ms Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand play took 2 milliseconds Apr 12 23:48:08 volumio volumio[892]: info: Apr 12 23:48:08 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:08 volumio volumio[892]: info: Apr 12 23:48:08 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand status took 9 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand status took 4 milliseconds Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":193,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Never Gonna Say I'm Sorry","artist":"Ace Of Base","album":"Дискотека: 100 Хитов 80-х (2018)","uri":"USB/364EDA5B4EDA1389/Новая папка/010. Ace Of Base - Never Gonna Say I'm Sorry.mp3","trackType":"mp3"} Apr 12 23:48:08 volumio volumio[892]: verbose: CURRENT POSITION 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:08 volumio volumio[892]: info: ------------------------------ 19ms Apr 12 23:48:08 volumio volumio[892]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 23:48:08 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:08 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":193,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Never Gonna Say I'm Sorry","artist":"Ace Of Base","album":"Дискотека: 100 Хитов 80-х (2018)","uri":"USB/364EDA5B4EDA1389/Новая папка/010. Ace Of Base - Never Gonna Say I'm Sorry.mp3","trackType":"mp3"} Apr 12 23:48:08 volumio volumio[892]: verbose: CURRENT POSITION 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:08 volumio volumio[892]: info: Received an update from plugin. extracting info from payload Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:08 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:08 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:08 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:08 volumio volumio[892]: info: ------------------------------ 24ms Apr 12 23:48:26 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:48:26 volumio volumio[892]: info: CURURI: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso Apr 12 23:48:26 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:29 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:48:29 volumio volumio[892]: info: CURURI: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO Apr 12 23:48:29 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:31 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 23:48:31 volumio volumio[892]: info: CURURI: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS Apr 12 23:48:31 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:31 volumio volumio[892]: info: Preloading song: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:31 volumio volumio[892]: info: Preloading song: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:31 volumio volumio[892]: info: Preloading song: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:31 volumio volumio[892]: info: Preloading song: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:31 volumio volumio[892]: info: Preloading song: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:31 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV in service mpd Apr 12 23:48:31 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FBEEPS%2FBEEPMETA.WAV&metadata=false Apr 12 23:48:31 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:31 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV in service mpd Apr 12 23:48:31 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FBEEPS%2FCLICK.WAV&metadata=false Apr 12 23:48:31 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:32 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV in service mpd Apr 12 23:48:32 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FBEEPS%2FDOUBLECLICK.WAV&metadata=false Apr 12 23:48:32 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:32 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV in service mpd Apr 12 23:48:32 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FBEEPS%2FLILBEEP.WAV&metadata=false Apr 12 23:48:32 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:32 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV in service mpd Apr 12 23:48:32 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FBEEPS%2FOOMPH.WAV&metadata=false Apr 12 23:48:32 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:36 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::serviceStop Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::serviceStop Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::stop Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:36 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:36 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:36 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::play index 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:36 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:36 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:36 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:36 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:36 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:36 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:36 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:36 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:36 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand stop took 35 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand stop took 6 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 5 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand clear took 3 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV" Apr 12 23:48:36 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 6ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV" took 5 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:36 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 20ms Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 12ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand play took 9 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 26 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 463 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand playlistinfo took 462 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BEEPMETA.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV","trackType":"WAV"} Apr 12 23:48:36 volumio volumio[892]: verbose: CURRENT POSITION 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 492ms Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 3ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Apr 12 23:48:36 volumio volumio[892]: 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} Apr 12 23:48:36 volumio volumio[892]: verbose: CURRENT POSITION 0 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 478ms Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 11ms Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand stop took 6 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand clear took 3 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" Apr 12 23:48:36 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 4ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" took 4 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 5ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand play took 5 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 40 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:36 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:36 volumio volumio[892]: info: Apr 12 23:48:36 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 45 milliseconds Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: 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} Apr 12 23:48:36 volumio volumio[892]: verbose: CURRENT POSITION 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: No code Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 70ms Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 74ms Apr 12 23:48:36 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 31ms Apr 12 23:48:36 volumio volumio[892]: info: sendMpdCommand status took 28 milliseconds Apr 12 23:48:36 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:36 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: 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} Apr 12 23:48:36 volumio volumio[892]: verbose: CURRENT POSITION 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: No code Apr 12 23:48:36 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:36 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:36 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:36 volumio volumio[892]: info: ------------------------------ 45ms Apr 12 23:48:50 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:50 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:50 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:50 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:50 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:50 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::play index 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:50 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:50 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:50 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:50 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:50 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:50 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:50 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand stop took 2 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:50 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand clear took 1 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" Apr 12 23:48:50 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:50 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 4ms Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" took 4 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 4ms Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand play took 4 milliseconds Apr 12 23:48:50 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand status took 3 milliseconds Apr 12 23:48:50 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand status took 38 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:50 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:50 volumio volumio[892]: info: Apr 12 23:48:50 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:50 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:50 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 46ms Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 42ms Apr 12 23:48:50 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 3ms Apr 12 23:48:50 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds Apr 12 23:48:50 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:50 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: 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} Apr 12 23:48:50 volumio volumio[892]: verbose: CURRENT POSITION 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:50 volumio volumio[892]: info: No code Apr 12 23:48:50 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:50 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:50 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:50 volumio volumio[892]: info: ------------------------------ 12ms Apr 12 23:48:52 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:52 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:52 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:52 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:52 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:52 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:52 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:52 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::play index 1 Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:52 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:52 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:52 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:52 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:52 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:52 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:52 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:52 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:52 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:52 volumio volumio[892]: info: sendMpdCommand stop took 4 milliseconds Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:52 volumio volumio[892]: info: Apr 12 23:48:52 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:52 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:52 volumio volumio[892]: info: sendMpdCommand clear took 1 milliseconds Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" Apr 12 23:48:52 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:52 volumio volumio[892]: info: Apr 12 23:48:52 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:52 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:52 volumio volumio[892]: info: ------------------------------ 9ms Apr 12 23:48:52 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV" took 8 milliseconds Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:52 volumio volumio[892]: info: ------------------------------ 7ms Apr 12 23:48:52 volumio volumio[892]: info: sendMpdCommand play took 6 milliseconds Apr 12 23:48:52 volumio volumio[892]: info: Apr 12 23:48:52 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:52 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:52 volumio volumio[892]: info: Apr 12 23:48:52 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:52 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:53 volumio volumio[892]: info: sendMpdCommand status took 38 milliseconds Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:53 volumio volumio[892]: info: Apr 12 23:48:53 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:53 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:53 volumio volumio[892]: info: Apr 12 23:48:53 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:53 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:53 volumio volumio[892]: info: sendMpdCommand status took 38 milliseconds Apr 12 23:48:53 volumio volumio[892]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:53 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: 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} Apr 12 23:48:53 volumio volumio[892]: verbose: CURRENT POSITION 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: info: No code Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:53 volumio volumio[892]: info: ------------------------------ 64ms Apr 12 23:48:53 volumio volumio[892]: info: ------------------------------ 67ms Apr 12 23:48:53 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:53 volumio volumio[892]: info: ------------------------------ 31ms Apr 12 23:48:53 volumio volumio[892]: info: sendMpdCommand status took 31 milliseconds Apr 12 23:48:53 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:53 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: 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} Apr 12 23:48:53 volumio volumio[892]: verbose: CURRENT POSITION 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: info: No code Apr 12 23:48:53 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:53 volumio volumio[892]: info: CorePlayQueue::getTrack 1 Apr 12 23:48:53 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:53 volumio volumio[892]: info: ------------------------------ 45ms Apr 12 23:48:56 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:56 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:56 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:56 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:56 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:56 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:56 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:56 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::play index 2 Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:56 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:56 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:56 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:56 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:56 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:56 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand stop took 6 milliseconds Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:56 volumio volumio[892]: info: Prefetching next song Apr 12 23:48:56 volumio volumio[892]: info: DOING PREFETCH IN MPD Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV" Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:56 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:56 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand clear took 2 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV" took 1 milliseconds Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV" Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:56 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:56 volumio volumio[892]: info: ------------------------------ 2ms Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV" took 2 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand consume 1 took 2 milliseconds Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:56 volumio volumio[892]: info: ------------------------------ 4ms Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand play took 3 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:56 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand status took 21 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:56 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:56 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand status took 232 milliseconds Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:56 volumio volumio[892]: info: Apr 12 23:48:56 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand playlistinfo took 235 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:56 volumio volumio[892]: info: ------------------------------ 233ms Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand status took 234 milliseconds Apr 12 23:48:56 volumio volumio[892]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:56 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:56 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:56 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:56 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"8 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DOUBLECLICK.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV","trackType":"WAV"} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DOUBLECLICK.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV","trackType":"WAV"} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:57 volumio volumio[892]: info: Received an update from plugin. extracting info from payload Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 273ms Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 255ms Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand status took 18 milliseconds Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DOUBLECLICK.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV","trackType":"WAV"} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:57 volumio volumio[892]: info: Received an update from plugin. extracting info from payload Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 265ms Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DOUBLECLICK.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV","trackType":"WAV"} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:57 volumio volumio[892]: info: Received an update from plugin. extracting info from payload Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 2 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 56ms Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:57 volumio volumio[892]: info: Apr 12 23:48:57 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:57 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:57 volumio volumio[892]: info: Apr 12 23:48:57 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:57 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 5ms Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:57 volumio volumio[892]: 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} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 3 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 19ms Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand stop took 15 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:57 volumio volumio[892]: info: Apr 12 23:48:57 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:57 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand clear took 3 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV" Apr 12 23:48:57 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:57 volumio volumio[892]: info: Apr 12 23:48:57 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:57 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 2ms Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV" took 1 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 3ms Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand play took 4 milliseconds Apr 12 23:48:57 volumio volumio[892]: info: Apr 12 23:48:57 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand status took 1 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:57 volumio volumio[892]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 23:48:57 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:57 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:57 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"OOMPH.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV","trackType":"WAV"} Apr 12 23:48:57 volumio volumio[892]: verbose: CURRENT POSITION 4 Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:48:57 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:57 volumio volumio[892]: info: ------------------------------ 12ms Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:57 volumio volumio[892]: info: CorePlayQueue::getTrack 5 Apr 12 23:48:58 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:58 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:58 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:58 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:59 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:59 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 2ms Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand status took 1 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:48:59 volumio volumio[892]: 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} Apr 12 23:48:59 volumio volumio[892]: verbose: CURRENT POSITION 4 Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 11ms Apr 12 23:48:59 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:59 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:59 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:59 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:48:59 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:59 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:48:59 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:59 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:48:59 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:59 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::play index 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:48:59 volumio volumio[892]: info: Preload queue cleared Apr 12 23:48:59 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:59 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand stop took 2 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:59 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand clear took 5 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV" Apr 12 23:48:59 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:59 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 6ms Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV" took 4 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 7ms Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand play took 5 milliseconds Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand status took 28 milliseconds Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand status took 200 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:48:59 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:48:59 volumio volumio[892]: info: Apr 12 23:48:59 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand playlistinfo took 203 milliseconds Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:48:59 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:59 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 234ms Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 207ms Apr 12 23:48:59 volumio volumio[892]: error: updateQueue error: null Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 4ms Apr 12 23:48:59 volumio volumio[892]: info: sendMpdCommand status took 4 milliseconds Apr 12 23:48:59 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:48:59 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: 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} Apr 12 23:48:59 volumio volumio[892]: verbose: CURRENT POSITION 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: No code Apr 12 23:48:59 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:48:59 volumio volumio[892]: info: CorePlayQueue::getTrack 3 Apr 12 23:48:59 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:48:59 volumio volumio[892]: info: ------------------------------ 16ms Apr 12 23:49:01 volumio volumio[892]: info: Preload queue cleared Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:49:01 volumio volumio[892]: info: Preload queue cleared Apr 12 23:49:01 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:49:01 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/BEEPMETA.WAV Apr 12 23:49:01 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:49:01 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/CLICK.WAV Apr 12 23:49:01 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:49:01 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/DOUBLECLICK.WAV Apr 12 23:49:01 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:49:01 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/LILBEEP.WAV Apr 12 23:49:01 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:49:01 volumio volumio[892]: info: Using cached record of: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrackBlock Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioPlay Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::play index 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::play index undefined Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::clearAddPlayTracks USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand stop took 6 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 23:49:01 volumio volumio[892]: info: Apr 12 23:49:01 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:49:01 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand clear took 1 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV" Apr 12 23:49:01 volumio volumio[892]: error: updateQueue error: null Apr 12 23:49:01 volumio volumio[892]: info: Apr 12 23:49:01 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:49:01 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:49:01 volumio volumio[892]: info: ------------------------------ 5ms Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand add "USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV" took 4 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play Apr 12 23:49:01 volumio volumio[892]: info: ------------------------------ 10ms Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand play took 9 milliseconds Apr 12 23:49:01 volumio volumio[892]: info: Apr 12 23:49:01 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:49:01 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:49:01 volumio volumio[892]: info: Apr 12 23:49:01 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand status took 38 milliseconds Apr 12 23:49:01 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand status took 4 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:49:01 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"OOMPH.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV","trackType":"WAV"} Apr 12 23:49:01 volumio volumio[892]: verbose: CURRENT POSITION 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Apr 12 23:49:01 volumio volumio[892]: info: ------------------------------ 47ms Apr 12 23:49:01 volumio volumio[892]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 23:49:01 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo Apr 12 23:49:01 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":1,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"OOMPH.WAV","artist":null,"album":null,"uri":"USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/BEEPS/OOMPH.WAV","trackType":"WAV"} Apr 12 23:49:01 volumio volumio[892]: verbose: CURRENT POSITION 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::syncState stateService play Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:49:01 volumio volumio[892]: info: Received an update from plugin. extracting info from payload Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:49:01 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:49:01 volumio volumio[892]: info: ------------------------------ 26ms Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:01 volumio volumio[892]: info: CorePlayQueue::getTrack 5 Apr 12 23:49:02 volumio volumio[892]: info: Apr 12 23:49:02 volumio volumio[892]: ---------------------------- MPD announces system playlist update Apr 12 23:49:02 volumio volumio[892]: info: Ignoring MPD Status Update Apr 12 23:49:02 volumio volumio[892]: info: Apr 12 23:49:02 volumio volumio[892]: ---------------------------- MPD announces state update: player Apr 12 23:49:02 volumio volumio[892]: info: ControllerMpd::getState Apr 12 23:49:02 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Apr 12 23:49:02 volumio volumio[892]: error: updateQueue error: null Apr 12 23:49:02 volumio volumio[892]: info: ------------------------------ 3ms Apr 12 23:49:02 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds Apr 12 23:49:02 volumio volumio[892]: verbose: ControllerMpd::parseState Apr 12 23:49:02 volumio volumio[892]: info: ControllerMpd::pushState Apr 12 23:49:02 volumio volumio[892]: info: CoreCommandRouter::servicePushState Apr 12 23:49:02 volumio volumio[892]: info: CoreStateMachine::pushState Apr 12 23:49:02 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:02 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 23:49:02 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:49:03 volumio volumio[892]: info: CorePlayQueue::getTrack 4 Apr 12 23:49:03 volumio volumio[892]: 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} Apr 12 23:49:03 volumio volumio[892]: verbose: CURRENT POSITION 4 Apr 12 23:49:03 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Apr 12 23:49:03 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play Apr 12 23:49:03 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Apr 12 23:49:03 volumio volumio[892]: info: CoreStateMachine::stPlaybackTimer Apr 12 23:49:03 volumio volumio[892]: info: ------------------------------ 16ms Apr 12 23:49:07 volumio volumio[892]: info: Preload queue cleared Apr 12 23:49:07 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 23:49:07 volumio volumio[892]: info: CoreStateMachine::ClearQueue Apr 12 23:49:07 volumio volumio[892]: info: CoreStateMachine::stop Apr 12 23:49:07 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 23:49:07 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue Apr 12 23:49:07 volumio volumio[892]: info: CorePlayQueue::saveQueue Apr 12 23:49:07 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue Apr 12 23:49:07 volumio volumio[892]: info: CoreStateMachine::addQueueItems Apr 12 23:49:07 volumio volumio[892]: info: CorePlayQueue::addQueueItems Apr 12 23:49:07 volumio volumio[892]: info: Preload queue cleared Apr 12 23:49:07 volumio volumio[892]: info: Adding Item to queue: music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/SOUNDSSCHEME Apr 12 23:49:07 volumio volumio[892]: info: Exploding uri music-library/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/SOUNDSSCHEME in service mpd Apr 12 23:49:07 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FSOUNDSSCHEME&metadata=false Apr 12 23:49:07 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/SOUNDSSCHEME Apr 12 23:49:07 volumio volumio[892]: info: ALBUMART /albumart?cacheid=325&web=//extralarge&path=%2Fmnt%2FUSB%2F364EDA5B4EDA1389%2F%5BFOST.CLUB%5DWPI%20StaforceTEAM%20%5B20.11.2020%5D.iso%2FAUDIO%2FSOUNDSSCHEME&metadata=false Apr 12 23:49:07 volumio volumio[892]: info: URI /mnt/USB/364EDA5B4EDA1389/[FOST.CLUB]WPI StaforceTEAM [20.11.2020].iso/AUDIO/SOUNDSSCHEME Apr 12 23:49:07 volumio volumio[892]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 23:49:07 volumio volumio[892]: Error: Unable to resolve or reject the same promise twice Apr 12 23:49:07 volumio volumio[892]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 12 23:49:07 volumio volumio[892]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Apr 12 23:49:07 volumio volumio[892]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 12 23:49:07 volumio volumio[892]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 12 23:49:07 volumio volumio[892]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 12 23:49:07 volumio volumio[892]: at Socket.emit (events.js:400:28) Apr 12 23:49:07 volumio volumio[892]: at addChunk (internal/streams/readable.js:293:12) Apr 12 23:49:07 volumio volumio[892]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 12 23:49:07 volumio volumio[892]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 12 23:49:07 volumio volumio[892]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 12 23:49:07 volumio volumio[892]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 23:49:07 volumio sudo[32048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-12 23:48 Apr 12 23:49:07 volumio sudo[32048]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"