-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-03-30 10:29:55 CET. -- Mar 30 10:28:21 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.58 UA: okhttp/4.9.2 Total Clients: 4 Mar 30 10:28:21 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Mar 30 10:28:21 volumio volumio[727]: info: CorePlayQueue::getTrack 8 Mar 30 10:28:21 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 30 10:28:21 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 30 10:28:46 volumio volumio[727]: info: CorePlayQueue::getTrack 8 Mar 30 10:28:46 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:46 volumio volumio[727]: info: Prefetching next song Mar 30 10:28:46 volumio volumio[727]: info: DOING PREFETCH IN MPD Mar 30 10:28:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac" Mar 30 10:28:46 volumio volumio[727]: info: Mar 30 10:28:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:46 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:46 volumio volumio[727]: info: sendMpdCommand add "NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac" took 2 milliseconds Mar 30 10:28:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 30 10:28:46 volumio volumio[727]: info: Mar 30 10:28:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:46 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:46 volumio volumio[727]: info: Mar 30 10:28:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:46 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:46 volumio volumio[727]: info: ------------------------------ 8ms Mar 30 10:28:46 volumio volumio[727]: info: sendMpdCommand consume 1 took 5 milliseconds Mar 30 10:28:46 volumio volumio[727]: info: ------------------------------ 4ms Mar 30 10:28:46 volumio volumio[727]: info: ------------------------------ 3ms Mar 30 10:28:50 volumio volumio[727]: info: CoreStateMachine::startPlaybackTimer Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:51 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:51 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:28:51 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:28:51 volumio volumio[727]: info: Mar 30 10:28:51 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 7ms Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand status took 7 milliseconds Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 6ms Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand status took 5 milliseconds Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 4ms Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 30 10:28:51 volumio volumio[727]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:28:51 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"503 Kbps","isStreaming":false,"title":"Bento Box","artist":"LunchBox","album":"Anyways","uri":"NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac","trackType":"flac"} Mar 30 10:28:51 volumio volumio[727]: verbose: CURRENT POSITION 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:28:51 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"503 Kbps","isStreaming":false,"title":"Bento Box","artist":"LunchBox","album":"Anyways","uri":"NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac","trackType":"flac"} Mar 30 10:28:51 volumio volumio[727]: verbose: CURRENT POSITION 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:28:51 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"503 Kbps","isStreaming":false,"title":"Bento Box","artist":"LunchBox","album":"Anyways","uri":"NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac","trackType":"flac"} Mar 30 10:28:51 volumio volumio[727]: verbose: CURRENT POSITION 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:28:51 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:28:51 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 58ms Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 57ms Mar 30 10:28:51 volumio volumio[727]: info: ------------------------------ 57ms Mar 30 10:28:58 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 30 10:28:58 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 30 10:28:58 volumio volumio[727]: info: Discovery: Getting this device information Mar 30 10:28:58 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Mar 30 10:28:58 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:28:58 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 30 10:29:01 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.58 UA: okhttp/4.9.2 Total Clients: 5 Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::volumioGetVisibleSources Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Mar 30 10:29:01 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 30 10:29:01 volumio volumio[727]: info: Received Get System Info Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 30 10:29:01 volumio volumio[727]: info: Discovery: Getting this device information Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Mar 30 10:29:01 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Mar 30 10:29:01 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:29:01 volumio volumio[727]: info: Listing playlists Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:29:01 volumio volumio[727]: info: CoreStateMachine::getQueue Mar 30 10:29:01 volumio volumio[727]: info: CorePlayQueue::getQueue Mar 30 10:29:01 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 30 10:29:13 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 30 10:29:13 volumio volumio[727]: info: CURURI: music-library/NAS/Public/Nouveau dossier (2) Mar 30 10:29:13 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:29 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::ClearQueue Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::stop Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrackBlock Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::serviceStop Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 9 Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::serviceStop Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::stop Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand stop Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::saveQueue Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::addQueueItems Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::addQueueItems Mar 30 10:29:29 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:29 volumio volumio[727]: info: Adding Item to queue: music-library/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB) Mar 30 10:29:29 volumio volumio[727]: info: Exploding uri music-library/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB) in service mpd Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%201%2F01.%20feed1.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%201%2F02.%20c16%20deep%20tread.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/02. c16 deep tread.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%201%2F03.%2013x0%20step.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/03. 13x0 step.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%201%2F04.%20pendulu%20hv%20moda.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/04. pendulu hv moda.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%201%2F05.%20curvcaten.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/05. curvcaten.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%202%2F01.%20elyc6%200nset.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 2/01. elyc6 0nset.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%202%2F02.%20chimer%201-5-1.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 2/02. chimer 1-5-1.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%202%2F03.%20c7b2.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 2/03. c7b2.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%203%2F01.%20eastre.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 3/01. eastre.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%203%2F02.%20TBM2.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 3/02. TBM2.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%203%2F03.%20mesh%20cinereaL.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 3/03. mesh cinereaL.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%204%2F01.%20acdwn2.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 4/01. acdwn2.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%204%2F02.%20foldfree%20casual.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 4/02. foldfree casual.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%204%2F03.%20latentcall.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 4/03. latentcall.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%204%2F04.%20artov%20chain.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 4/04. artov chain.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%204%2F05.%207th%20slip.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 4/05. 7th slip.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%205%2F01.%20pendulu%20casual.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 5/01. pendulu casual.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%205%2F02.%20spTh.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 5/02. spTh.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%205%2F03.%20spaces%20how%20V.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 5/03. spaces how V.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%205%2F04.%20freulaeux.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 5/04. freulaeux.flac Mar 30 10:29:29 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Autechre/elseq%201-5/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(2)%2FAutechre%20-%20elseq%201-5%20(2016)(FLAC)(WEB)%2Felseq%205%2F05.%20oneum.flac&metadata=false Mar 30 10:29:29 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 5/05. oneum.flac Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand stop took 119 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::saveQueue Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrackBlock Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPlay Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::play index 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::stop Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::play index undefined Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::startPlaybackTimer Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::clearAddPlayTracks NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand stop Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 22 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand stop took 7 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand clear Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand clear took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac" Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bento Box","artist":"LunchBox","album":"Anyways","uri":"NAS/Public/Downtempo/2004 - Lunchbox - Anyways [AG030] [FLAC]/10 Lunchbox - Bento Box.flac","trackType":"flac"} Mar 30 10:29:29 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:29 volumio volumio[727]: info: No code Mar 30 10:29:29 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:29 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:29 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:29 volumio volumio[727]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 30 10:29:29 volumio volumio[727]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 68ms Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 33ms Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 31ms Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: error: updateQueue error: null Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 31ms Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand add "NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac" took 30 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 11ms Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 10ms Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand play Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces system playlist update Mar 30 10:29:29 volumio volumio[727]: info: Ignoring MPD Status Update Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 21ms Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand play took 18 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 18ms Mar 30 10:29:29 volumio volumio[727]: info: ------------------------------ 16ms Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 8 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 6 milliseconds Mar 30 10:29:29 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:29 volumio volumio[727]: info: Mar 30 10:29:29 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:29 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:29 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand status took 18 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand status took 13 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand status took 13 milliseconds Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"165 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"174 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:29:30 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"174 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:29:30 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 43ms Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 48ms Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 47ms Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 30 10:29:30 volumio volumio[727]: info: sendMpdCommand playlistinfo took 42 milliseconds Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"174 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:29:30 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"174 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:29:30 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"174 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:30 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState stateService play Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:29:30 volumio volumio[727]: info: Received an update from plugin. extracting info from payload Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:30 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:30 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 95ms Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 90ms Mar 30 10:29:30 volumio volumio[727]: info: ------------------------------ 88ms Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::volumioPause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::servicePause Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::servicePause Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::pause Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand pause Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand pause took 1 milliseconds Mar 30 10:29:40 volumio volumio[727]: info: Mar 30 10:29:40 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:40 volumio volumio[727]: info: Mar 30 10:29:40 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:40 volumio volumio[727]: info: Mar 30 10:29:40 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::getState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseState Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 30 10:29:40 volumio volumio[727]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:40 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11371,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"986 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11371,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"986 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:40 volumio volumio[727]: info: ControllerMpd::pushState Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11371,"duration":698,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"986 Kbps","isStreaming":false,"title":"feed1","artist":"Autechre","album":"elseq 1-5","uri":"NAS/Public/Nouveau dossier (2)/Autechre - elseq 1-5 (2016)(FLAC)(WEB)/elseq 1/01. feed1.flac","trackType":"flac"} Mar 30 10:29:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus pause Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:40 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:40 volumio volumio[727]: info: ------------------------------ 25ms Mar 30 10:29:40 volumio volumio[727]: info: ------------------------------ 24ms Mar 30 10:29:40 volumio volumio[727]: info: ------------------------------ 23ms Mar 30 10:29:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 30 10:29:43 volumio volumio[727]: info: CURURI: music-library/NAS/Public/Nouveau dossier (3) Mar 30 10:29:43 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:55 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:55 volumio volumio[727]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::ClearQueue Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::stop Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::getTrackBlock Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::pushState Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:55 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:29:55 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::serviceStop Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Mar 30 10:29:55 volumio volumio[727]: info: CoreCommandRouter::serviceStop Mar 30 10:29:55 volumio volumio[727]: info: ControllerMpd::stop Mar 30 10:29:55 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand stop Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::saveQueue Mar 30 10:29:55 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue Mar 30 10:29:55 volumio volumio[727]: info: CoreStateMachine::addQueueItems Mar 30 10:29:55 volumio volumio[727]: info: CorePlayQueue::addQueueItems Mar 30 10:29:55 volumio volumio[727]: info: Preload queue cleared Mar 30 10:29:55 volumio volumio[727]: info: Adding Item to queue: music-library/NAS/Public/Nouveau dossier (3)/Loess - Loess - 2002 Mar 30 10:29:55 volumio volumio[727]: info: Exploding uri music-library/NAS/Public/Nouveau dossier (3)/Loess - Loess - 2002 in service mpd Mar 30 10:29:55 volumio volumio[727]: info: Mar 30 10:29:55 volumio volumio[727]: ---------------------------- MPD announces state update: player Mar 30 10:29:55 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Loess/Loess/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(3)%2FLoess%20-%20Loess%20-%202002%2FLoess%20-%20Loess.cue&metadata=false Mar 30 10:29:55 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (3)/Loess - Loess - 2002/Loess - Loess.cue Mar 30 10:29:55 volumio volumio[727]: info: ALBUMART /albumart?cacheid=509&web=Loess/Loess/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FNouveau%20dossier%20(3)%2FLoess%20-%20Loess%20-%202002%2FLoess%20-%20Loess.cue&metadata=false Mar 30 10:29:55 volumio volumio[727]: info: URI /mnt/NAS/Public/Nouveau dossier (3)/Loess - Loess - 2002/Loess - Loess.cue Mar 30 10:29:55 volumio volumio[727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:29:55 volumio volumio[727]: Error: Unable to resolve or reject the same promise twice Mar 30 10:29:55 volumio volumio[727]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 30 10:29:55 volumio volumio[727]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Mar 30 10:29:55 volumio volumio[727]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 30 10:29:55 volumio volumio[727]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 30 10:29:55 volumio volumio[727]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 30 10:29:55 volumio volumio[727]: at Socket.emit (events.js:315:20) Mar 30 10:29:55 volumio volumio[727]: at addChunk (internal/streams/readable.js:309:12) Mar 30 10:29:55 volumio volumio[727]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 30 10:29:55 volumio volumio[727]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 30 10:29:55 volumio volumio[727]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 30 10:29:55 volumio volumio[727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:29:55 volumio sudo[12873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 10:28 Mar 30 10:29:55 volumio sudo[12873]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"