-- Logs begin at Wed 2024-10-23 18:32:56 UTC, end at Fri 2024-10-25 17:17:58 UTC. -- Oct 25 17:16:08 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 25 17:16:08 volumio volumio[8868]: info: CURURI: albums://Various%20Artists/Burmester%20Reference%20CD%20III Oct 25 17:16:08 volumio volumio[8868]: info: Preload queue cleared Oct 25 17:16:08 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:08 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:08 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:08 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:09 volumio volumio[8868]: info: No valid Plugin REST Endpoint Oct 25 17:16:10 volumio volumio[8868]: info: Preload queue cleared Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::ClearQueue Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::stPlaybackTimer Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::updateTrackBlock Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrackBlock Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 13 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::serviceStop Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 13 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::serviceStop Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::stop Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::clearPlayQueue Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::saveQueue Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushQueue Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::addQueueItems Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::addQueueItems Oct 25 17:16:10 volumio volumio[8868]: info: Preload queue cleared Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/INTERNAL/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/INTERNAL/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/SD_CARD/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/SD_CARD/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/01 A Time For Love.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/01 A Time For Love.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/02 Nicolo Paganini Works for Violin and Guitar, 1. Sonata Concertata.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/02 Nicolo Paganini Works for Violin and Guitar, 1. Sonata Concertata.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/03 Jealous, 3. Early One Morning.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/03 Jealous, 3. Early One Morning.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/03 Jealous, 3. Early One Morning.wav Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/04 It's The Talk of the Town.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/04 It's The Talk of the Town.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/05 Gioacchino Rossini, Sonate per Archi, Sonata I G-Dur.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/05 Gioacchino Rossini, Sonate per Archi, Sonata I G-Dur.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/06 Call Me, 9. The Planet.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/06 Call Me, 9. The Planet.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/07 Nikolai Rimsky Korsakov, Scheherazade, IV. Allegro Molto.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/07 Nikolai Rimsky Korsakov, Scheherazade, IV. Allegro Molto.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/08 Orgelwerke von Bach.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/08 Orgelwerke von Bach.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac Oct 25 17:16:10 volumio volumio[8868]: info: Adding Item to queue: music-library/NAS/Win/Compilations/Burmester Reference CD III/10 Poem of Chinese Drums.flac Oct 25 17:16:10 volumio volumio[8868]: info: Using cached record of: music-library/NAS/Win/Compilations/Burmester Reference CD III/10 Poem of Chinese Drums.flac Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushQueue Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::saveQueue Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::updateTrackBlock Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrackBlock Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPlay Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::play index 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::play index undefined Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::startPlaybackTimer Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::clearAddPlayTracks NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand stop took 29 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand stop took 8 milliseconds Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand status took 6 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand clear took 6 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand status took 5 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand status took 4 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand status took 3 milliseconds Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand add "NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac" Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 25 17:16:10 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState stateService stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: No code Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 25 17:16:10 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState stateService stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: No code Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 25 17:16:10 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState stateService stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus stop Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: No code Oct 25 17:16:10 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:10 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:10 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 39ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 38ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 38ms Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: error: updateQueue error: null Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 45ms Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand add "NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac" took 44 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 8ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 7ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 6ms Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand play Oct 25 17:16:10 volumio volumio[8868]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 55ms Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: Oct 25 17:16:10 volumio volumio[8868]: ---------------------------- MPD announces system playlist update Oct 25 17:16:10 volumio volumio[8868]: info: Ignoring MPD Status Update Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 30ms Oct 25 17:16:10 volumio volumio[8868]: info: sendMpdCommand play took 27 milliseconds Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 26ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 25ms Oct 25 17:16:10 volumio volumio[8868]: info: ------------------------------ 24ms Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 25 17:16:11 volumio volumio[8868]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 25 17:16:11 volumio volumio[8868]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 25 17:16:11 volumio volumio[8868]: info: FusionDsp - else 1 -2.5 Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 14 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 14 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 12 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 10 milliseconds Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: Oct 25 17:16:11 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 10 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 7 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 6 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand status took 5 milliseconds Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"631 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus stop Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 48ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 58ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 57ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 56ms Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 25 17:16:11 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:16:11 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:16:11 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:16:11 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:16:11 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 80ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 77ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 77ms Oct 25 17:16:11 volumio volumio[8868]: info: ------------------------------ 77ms Oct 25 17:16:11 volumio volumio[8868]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:41 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:16:57 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 17:17:00 volumio volumio[8868]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/0.7.3/volumio/buster/armhf Oct 25 17:17:03 volumio volumio[8868]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/0.7.3/volumio/buster/armhf Oct 25 17:17:03 volumio volumio[8868]: info: Folder /tmp/plugins removed Oct 25 17:17:06 volumio volumio[8868]: info: Check plugin dependencies Oct 25 17:17:06 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 17:17:06 volumio volumio[8868]: info: Checking if plugin already exists Oct 25 17:17:06 volumio volumio[8868]: info: Rename folder Oct 25 17:17:06 volumio volumio[8868]: info: MPD Database updated - AlbumList cache refreshed Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: database Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: update Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: MPD Database updated - AlbumList cache refreshed Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: database Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: update Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: MPD Database updated - AlbumList cache refreshed Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: database Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: update Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: MPD Database updated - AlbumList cache refreshed Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: database Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: Oct 25 17:17:06 volumio volumio[8868]: ---------------------------- MPD announces state update: update Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:06 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:06 volumio volumio[8868]: info: Folder /tmp/downloaded_plugin.zip removed Oct 25 17:17:06 volumio volumio[8868]: info: Move to category Oct 25 17:17:08 volumio volumio[8868]: info: Checking if install.sh is present Oct 25 17:17:08 volumio volumio[8868]: info: Executing install.sh Oct 25 17:17:08 volumio sudo[11244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Oct 25 17:17:08 volumio sudo[11244]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 17:17:10 volumio volumio[8868]: info: sendMpdCommand status took 3741 milliseconds Oct 25 17:17:10 volumio volumio[8868]: info: sendMpdCommand status took 3741 milliseconds Oct 25 17:17:10 volumio volumio[8868]: info: sendMpdCommand status took 3741 milliseconds Oct 25 17:17:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:10 volumio volumio[8868]: info: Command Router : Notfying DB Updatefalse Oct 25 17:17:10 volumio volumio[8868]: info: CoreCommandRouter::Close All Modals sent Oct 25 17:17:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:10 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:10 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:11 volumio volumio[8868]: info: sendMpdCommand status took 5236 milliseconds Oct 25 17:17:11 volumio volumio[8868]: info: sendMpdCommand status took 5235 milliseconds Oct 25 17:17:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:11 volumio volumio[8868]: info: Command Router : Notfying DB Updatefalse Oct 25 17:17:11 volumio volumio[8868]: info: CoreCommandRouter::Close All Modals sent Oct 25 17:17:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:11 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:11 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:12 volumio volumio[8868]: info: sendMpdCommand status took 6534 milliseconds Oct 25 17:17:12 volumio volumio[8868]: info: sendMpdCommand status took 6534 milliseconds Oct 25 17:17:12 volumio volumio[8868]: info: sendMpdCommand status took 6533 milliseconds Oct 25 17:17:12 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:12 volumio volumio[8868]: info: Command Router : Notfying DB Updatefalse Oct 25 17:17:12 volumio volumio[8868]: info: CoreCommandRouter::Close All Modals sent Oct 25 17:17:12 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:12 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:12 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:12 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand status took 7812 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand status took 7812 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand status took 7806 milliseconds Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:14 volumio volumio[8868]: info: Command Router : Notfying DB Updatefalse Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::Close All Modals sent Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 4147 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 4146 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 2660 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 2660 milliseconds Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57199,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"566 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57199,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"566 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57451,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57451,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8124ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8124ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8119ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8121ms Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 1550 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 1551 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 169 milliseconds Oct 25 17:17:14 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 169 milliseconds Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57588,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"599 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57588,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"599 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57840,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57840,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:14 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState stateService play Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus play Oct 25 17:17:14 volumio volumio[8868]: info: Received an update from plugin. extracting info from payload Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:14 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:14 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8204ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8206ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8202ms Oct 25 17:17:14 volumio volumio[8868]: info: ------------------------------ 8204ms Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:43 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: Enabling plugin now_playing Oct 25 17:17:49 volumio volumio[8868]: info: Loading plugin "now_playing"... Oct 25 17:17:49 volumio volumio[8868]: error: !!!! WARNING !!!! Oct 25 17:17:49 volumio volumio[8868]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Oct 25 17:17:49 volumio volumio[8868]: Require stack: Oct 25 17:17:49 volumio volumio[8868]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/app/pluginmanager.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/app/index.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/index.js Oct 25 17:17:49 volumio volumio[8868]: error: Stack trace: Error: Cannot find module 'geo-tz' Oct 25 17:17:49 volumio volumio[8868]: Require stack: Oct 25 17:17:49 volumio volumio[8868]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/app/pluginmanager.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/app/index.js Oct 25 17:17:49 volumio volumio[8868]: - /volumio/index.js Oct 25 17:17:49 volumio volumio[8868]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Oct 25 17:17:49 volumio volumio[8868]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Oct 25 17:17:49 volumio volumio[8868]: at Module.require (internal/modules/cjs/loader.js:952:19) Oct 25 17:17:49 volumio volumio[8868]: at require (internal/modules/cjs/helpers.js:88:18) Oct 25 17:17:49 volumio volumio[8868]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Oct 25 17:17:49 volumio volumio[8868]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Oct 25 17:17:49 volumio volumio[8868]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Oct 25 17:17:49 volumio volumio[8868]: at Module.load (internal/modules/cjs/loader.js:928:32) Oct 25 17:17:49 volumio volumio[8868]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Oct 25 17:17:49 volumio volumio[8868]: at Module.require (internal/modules/cjs/loader.js:952:19) Oct 25 17:17:49 volumio volumio[8868]: at require (internal/modules/cjs/helpers.js:88:18) Oct 25 17:17:49 volumio volumio[8868]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Oct 25 17:17:49 volumio volumio[8868]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 25 17:17:49 volumio volumio[8868]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 25 17:17:49 volumio volumio[8868]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 25 17:17:49 volumio volumio[8868]: error: !!!! WARNING !!!! Oct 25 17:17:49 volumio volumio[8868]: info: Done. Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:49 volumio volumio[8868]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::volumioPause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::stPlaybackTimer Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::servicePause Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::servicePause Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::pause Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand pause Oct 25 17:17:57 volumio volumio[8868]: info: Oct 25 17:17:57 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand pause took 8 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:57 volumio volumio[8868]: info: Oct 25 17:17:57 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:57 volumio volumio[8868]: info: Oct 25 17:17:57 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:57 volumio volumio[8868]: info: Oct 25 17:17:57 volumio volumio[8868]: ---------------------------- MPD announces state update: player Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::getState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand status Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand status took 17 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand status took 14 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand status took 11 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand status took 8 milliseconds Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseState Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 25 17:17:57 volumio volumio[8868]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:57 volumio volumio[8868]: verbose: ControllerMpd::parseTrackInfo Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108198,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:57 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState stateService pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108198,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:57 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState stateService pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108198,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:57 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState stateService pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:57 volumio volumio[8868]: info: ControllerMpd::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::servicePushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108198,"duration":603,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"09 Hope, 12. Stimela.flac","artist":"Compilations","album":"Burmester Reference CD III","uri":"NAS/Win/Compilations/Burmester Reference CD III/09 Hope, 12. Stimela.flac","trackType":"flac"} Oct 25 17:17:57 volumio volumio[8868]: verbose: CURRENT POSITION 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState stateService pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::syncState currentStatus pause Oct 25 17:17:57 volumio volumio[8868]: info: CoreStateMachine::pushState Oct 25 17:17:57 volumio volumio[8868]: info: CorePlayQueue::getTrack 11 Oct 25 17:17:57 volumio volumio[8868]: info: CoreCommandRouter::volumioPushState Oct 25 17:17:57 volumio volumio[8868]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null Oct 25 17:17:57 volumio volumio[8868]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null Oct 25 17:17:57 volumio volumio[8868]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null Oct 25 17:17:57 volumio volumio[8868]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null Oct 25 17:17:57 volumio volumio[8868]: info: ------------------------------ 123ms Oct 25 17:17:57 volumio volumio[8868]: info: ------------------------------ 117ms Oct 25 17:17:57 volumio volumio[8868]: info: ------------------------------ 114ms Oct 25 17:17:57 volumio volumio[8868]: info: ------------------------------ 110ms Oct 25 17:17:57 volumio volumio[8868]: info: Oct 25 17:17:57 volumio volumio[8868]: + geo-tz@8.1.2 Oct 25 17:17:57 volumio volumio[8868]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 17:17:57 volumio volumio[8868]: TypeError: Cannot read property 'broadcastMessage' of null Oct 25 17:17:57 volumio volumio[8868]: at /volumio/app/index.js:1515:32 Oct 25 17:17:57 volumio volumio[8868]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 25 17:17:57 volumio volumio[8868]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 25 17:17:57 volumio volumio[8868]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1513:13) Oct 25 17:17:57 volumio volumio[8868]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Oct 25 17:17:57 volumio volumio[8868]: at Tail. (/volumio/app/pluginmanager.js:860:24) Oct 25 17:17:57 volumio volumio[8868]: at Tail.emit (events.js:315:20) Oct 25 17:17:57 volumio volumio[8868]: at ReadStream. (/volumio/node_modules/tail/lib/tail.js:51:33) Oct 25 17:17:57 volumio volumio[8868]: at ReadStream.emit (events.js:315:20) Oct 25 17:17:57 volumio volumio[8868]: at addChunk (internal/streams/readable.js:309:12) Oct 25 17:17:57 volumio volumio[8868]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 25 17:17:57 volumio volumio[8868]: at ReadStream.Readable.push (internal/streams/readable.js:223:10) Oct 25 17:17:57 volumio volumio[8868]: at internal/fs/streams.js:226:14 Oct 25 17:17:57 volumio volumio[8868]: at FSReqCallback.wrapper [as oncomplete] (fs.js:539:5) Oct 25 17:17:57 volumio volumio[8868]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 17:17:58 volumio sudo[11317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 17:16 Oct 25 17:17:58 volumio sudo[11317]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"