-- Logs begin at Fri 2024-05-03 04:27:33 UTC, end at Mon 2026-01-12 06:37:39 UTC. -- Jan 12 06:36:01 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::ClearQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::stop Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::clearPlayQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::saveQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::addQueueItems Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::addQueueItems Jan 12 06:36:01 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_L.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_L.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Adding Item to queue: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_R.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: Using cached record of: music-library/INTERNAL/FusionDsp/tools/PinkNoise_44k_R.wav Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::saveQueue Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::updateTrackBlock Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrackBlock Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPlay Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::play index 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::stop Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::play index undefined Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::startPlaybackTimer Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand stop took 4 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand clear took 4 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav" Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: error: updateQueue error: null Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 14ms Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav" took 12 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 11ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 10ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand play Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:01 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:01 volumio-sever volumio[831]: error: Upnp client error: Error: This socket has been ended by the other party Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 20ms Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand play took 17 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 18ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 16ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 14ms Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 15 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 14 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 12 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 10 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: Jan 12 06:36:01 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:01 volumio-sever volumio[831]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Jan 12 06:36:01 volumio-sever volumio[831]: info: FusionDsp - ---- read samplerate from file: 384000 Jan 12 06:36:01 volumio-sever volumio[831]: info: camilladsp stopping service pid 1176... Jan 12 06:36:01 volumio-sever volumio[831]: info: camilladsp service terminated, instance 1 Jan 12 06:36:01 volumio-sever volumio[831]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 49 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 44 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 39 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 37 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand status took 34 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:01 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus stop Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:01 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:01 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:01 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:01 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:01 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:01 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 97ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 127ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 126ms Jan 12 06:36:01 volumio-sever volumio[831]: info: ------------------------------ 124ms Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 65 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 65 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 66 milliseconds Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:01 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:01 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:01 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:01 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:01 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:01 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:02 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:02 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:02 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:02 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:02 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:02 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:02 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:02 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:02 volumio-sever volumio[831]: info: ------------------------------ 187ms Jan 12 06:36:02 volumio-sever volumio[831]: info: ------------------------------ 173ms Jan 12 06:36:02 volumio-sever volumio[831]: info: ------------------------------ 171ms Jan 12 06:36:02 volumio-sever volumio[831]: info: ------------------------------ 168ms Jan 12 06:36:02 volumio-sever volumio[831]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jan 12 06:36:02 volumio-sever volumio[831]: 2+119 records in Jan 12 06:36:02 volumio-sever volumio[831]: 2+119 records out Jan 12 06:36:02 volumio-sever volumio[831]: 552920 bytes (553 kB, 540 KiB) copied, 0.00703918 s, 78.5 MB/s Jan 12 06:36:02 volumio-sever volumio[831]: info: camilladsp spawned new process with pid 1475, instance 1, run: true Jan 12 06:36:02 volumio-sever volumio[831]: info: camilladsp service started and running in background, instance 1 Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:09 volumio-sever volumio[831]: info: Prefetching next song Jan 12 06:36:09 volumio-sever volumio[831]: info: DOING PREFETCH IN MPD Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:09 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" took 4 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:09 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:09 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:09 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces state update: options Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 17ms Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand consume 1 took 16 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 12ms Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 10ms Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces state update: options Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces state update: options Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:09 volumio-sever volumio[831]: info: Jan 12 06:36:09 volumio-sever volumio[831]: ---------------------------- MPD announces state update: options Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand status took 16 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand status took 12 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand status took 9 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand status took 18 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8053,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:09 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:09 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8053,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:09 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:09 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8053,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:09 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:09 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 205ms Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 201ms Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 199ms Jan 12 06:36:09 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 175 milliseconds Jan 12 06:36:09 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:09 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8053,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_L_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_L_refL.wav","trackType":"wav"} Jan 12 06:36:09 volumio-sever volumio[831]: verbose: CURRENT POSITION 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:09 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:09 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 0 Jan 12 06:36:09 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:09 volumio-sever volumio[831]: info: ------------------------------ 249ms Jan 12 06:36:10 volumio-sever kernel: hwmon hwmon1: Undervoltage detected! Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::startPlaybackTimer Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:14 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:14 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:14 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:14 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:14 volumio-sever volumio[831]: info: Jan 12 06:36:14 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:14 volumio-sever volumio[831]: info: ------------------------------ 15ms Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand status took 13 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: ------------------------------ 12ms Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand status took 11 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: ------------------------------ 10ms Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand status took 9 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: ------------------------------ 9ms Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand status took 7 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:14 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_LR_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav","trackType":"wav"} Jan 12 06:36:14 volumio-sever volumio[831]: verbose: CURRENT POSITION 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:14 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_LR_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav","trackType":"wav"} Jan 12 06:36:14 volumio-sever volumio[831]: verbose: CURRENT POSITION 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:14 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_LR_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav","trackType":"wav"} Jan 12 06:36:14 volumio-sever volumio[831]: verbose: CURRENT POSITION 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:14 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_LR_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav","trackType":"wav"} Jan 12 06:36:14 volumio-sever volumio[831]: verbose: CURRENT POSITION 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:14 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:14 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:14 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:15 volumio-sever volumio[831]: info: ------------------------------ 189ms Jan 12 06:36:15 volumio-sever volumio[831]: info: ------------------------------ 186ms Jan 12 06:36:15 volumio-sever volumio[831]: info: ------------------------------ 185ms Jan 12 06:36:15 volumio-sever volumio[831]: info: ------------------------------ 183ms Jan 12 06:36:20 volumio-sever kernel: hwmon hwmon1: Voltage normalised Jan 12 06:36:25 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:25 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:25 volumio-sever volumio[831]: info: Prefetching next song Jan 12 06:36:25 volumio-sever volumio[831]: info: DOING PREFETCH IN MPD Jan 12 06:36:25 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav" Jan 12 06:36:25 volumio-sever volumio[831]: info: Jan 12 06:36:25 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:25 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:25 volumio-sever volumio[831]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav" took 3 milliseconds Jan 12 06:36:25 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 06:36:25 volumio-sever volumio[831]: info: Jan 12 06:36:25 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:25 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:25 volumio-sever volumio[831]: info: Jan 12 06:36:25 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:25 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:25 volumio-sever volumio[831]: info: Jan 12 06:36:25 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:25 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:25 volumio-sever volumio[831]: info: ------------------------------ 9ms Jan 12 06:36:25 volumio-sever volumio[831]: info: sendMpdCommand consume 1 took 7 milliseconds Jan 12 06:36:25 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:25 volumio-sever volumio[831]: info: ------------------------------ 5ms Jan 12 06:36:25 volumio-sever volumio[831]: info: ------------------------------ 4ms Jan 12 06:36:28 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume98 Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:28 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:28 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume99 Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:28 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:28 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:28 volumio-sever kernel: hwmon hwmon1: Undervoltage detected! Jan 12 06:36:29 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume100 Jan 12 06:36:29 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:29 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 1 Jan 12 06:36:29 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:29 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:30 volumio-sever volumio[831]: info: CoreStateMachine::startPlaybackTimer Jan 12 06:36:30 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:31 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:31 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:31 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:31 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:31 volumio-sever volumio[831]: info: Jan 12 06:36:31 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 33ms Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand status took 28 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 28ms Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand status took 23 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 23ms Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand status took 20 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 19ms Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand status took 14 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:31 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_R_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav","trackType":"wav"} Jan 12 06:36:31 volumio-sever volumio[831]: verbose: CURRENT POSITION 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:31 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_R_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav","trackType":"wav"} Jan 12 06:36:31 volumio-sever volumio[831]: verbose: CURRENT POSITION 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:31 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_R_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav","trackType":"wav"} Jan 12 06:36:31 volumio-sever volumio[831]: verbose: CURRENT POSITION 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:31 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":13,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"512kMeasSweep_30_to_20k_44k_PCM16_R_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_R_refL.wav","trackType":"wav"} Jan 12 06:36:31 volumio-sever volumio[831]: verbose: CURRENT POSITION 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:31 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:31 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:31 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 244ms Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 237ms Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 231ms Jan 12 06:36:31 volumio-sever volumio[831]: info: ------------------------------ 226ms Jan 12 06:36:35 volumio-sever kernel: hwmon hwmon1: Voltage normalised Jan 12 06:36:39 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 2 Jan 12 06:36:39 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:39 volumio-sever volumio[831]: info: Prefetching next song Jan 12 06:36:39 volumio-sever volumio[831]: info: DOING PREFETCH IN MPD Jan 12 06:36:39 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Jan 12 06:36:39 volumio-sever volumio[831]: info: Jan 12 06:36:39 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:39 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:39 volumio-sever volumio[831]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 3 milliseconds Jan 12 06:36:39 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 06:36:39 volumio-sever volumio[831]: info: Jan 12 06:36:39 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:39 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:39 volumio-sever volumio[831]: info: Jan 12 06:36:39 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:39 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:39 volumio-sever volumio[831]: info: Jan 12 06:36:39 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:39 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:39 volumio-sever volumio[831]: info: ------------------------------ 10ms Jan 12 06:36:39 volumio-sever volumio[831]: info: sendMpdCommand consume 1 took 8 milliseconds Jan 12 06:36:39 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:39 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:39 volumio-sever volumio[831]: info: ------------------------------ 4ms Jan 12 06:36:43 volumio-sever volumio[831]: info: CoreStateMachine::startPlaybackTimer Jan 12 06:36:43 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:44 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:44 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:44 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:44 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:44 volumio-sever volumio[831]: info: Jan 12 06:36:44 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 30ms Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand status took 26 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 26ms Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand status took 21 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 20ms Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand status took 16 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 16ms Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand status took 11 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:44 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Jan 12 06:36:44 volumio-sever volumio[831]: verbose: CURRENT POSITION 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:44 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Jan 12 06:36:44 volumio-sever volumio[831]: verbose: CURRENT POSITION 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:44 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Jan 12 06:36:44 volumio-sever volumio[831]: verbose: CURRENT POSITION 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:44 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Jan 12 06:36:44 volumio-sever volumio[831]: verbose: CURRENT POSITION 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:44 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:44 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:44 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 275ms Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 269ms Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 264ms Jan 12 06:36:44 volumio-sever volumio[831]: info: ------------------------------ 258ms Jan 12 06:36:45 volumio-sever kernel: hwmon hwmon1: Undervoltage detected! Jan 12 06:36:45 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:45 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:45 volumio-sever volumio[831]: info: Prefetching next song Jan 12 06:36:45 volumio-sever volumio[831]: info: DOING PREFETCH IN MPD Jan 12 06:36:45 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav" Jan 12 06:36:45 volumio-sever volumio[831]: info: Jan 12 06:36:45 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:45 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:45 volumio-sever volumio[831]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav" took 3 milliseconds Jan 12 06:36:45 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 06:36:45 volumio-sever volumio[831]: info: Jan 12 06:36:45 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:45 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:45 volumio-sever volumio[831]: info: Jan 12 06:36:45 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:45 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:45 volumio-sever volumio[831]: info: Jan 12 06:36:45 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:45 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:45 volumio-sever volumio[831]: info: ------------------------------ 14ms Jan 12 06:36:45 volumio-sever volumio[831]: info: sendMpdCommand consume 1 took 13 milliseconds Jan 12 06:36:45 volumio-sever volumio[831]: info: ------------------------------ 11ms Jan 12 06:36:45 volumio-sever volumio[831]: info: ------------------------------ 9ms Jan 12 06:36:45 volumio-sever volumio[831]: info: ------------------------------ 6ms Jan 12 06:36:49 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume99 Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:49 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:49 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume98 Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:49 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 3 Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:49 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumiologrotate[494]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 12 06:36:50 volumio-sever volumiologrotate[494]: ls: cannot access 'SEVER': No such file or directory Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::startPlaybackTimer Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume97 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume96 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume95 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:50 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:50 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:50 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces system playlist update Jan 12 06:36:50 volumio-sever volumio[831]: info: Ignoring MPD Status Update Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 26ms Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 23 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 23ms Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 21 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 20ms Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 10 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 9ms Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 7 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: Jan 12 06:36:50 volumio-sever volumio[831]: ---------------------------- MPD announces state update: player Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::getState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand status Jan 12 06:36:50 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume95 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 88 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 84 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 85 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 84 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 84 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 90 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 89 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: info: sendMpdCommand status took 87 milliseconds Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::parseState Jan 12 06:36:50 volumio-sever volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:50 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:50 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:50 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:50 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:50 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:50 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:50 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:50 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 346ms Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 344ms Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 342ms Jan 12 06:36:50 volumio-sever volumio[831]: info: ------------------------------ 331ms Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:50 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:50 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 275 milliseconds Jan 12 06:36:51 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 273 milliseconds Jan 12 06:36:51 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 273 milliseconds Jan 12 06:36:51 volumio-sever volumio[831]: info: sendMpdCommand playlistinfo took 272 milliseconds Jan 12 06:36:51 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:51 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:51 volumio-sever volumio[831]: verbose: ControllerMpd::parseTrackInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:51 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:51 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:51 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:51 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:51 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:51 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: ControllerMpd::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::servicePushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PinkNoise_44k_BOTH","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/PinkNoise_44k_BOTH.wav","trackType":"wav"} Jan 12 06:36:51 volumio-sever volumio[831]: verbose: CURRENT POSITION 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState stateService play Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::syncState currentStatus play Jan 12 06:36:51 volumio-sever volumio[831]: info: Received an update from plugin. extracting info from payload Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: ------------------------------ 533ms Jan 12 06:36:51 volumio-sever volumio[831]: info: ------------------------------ 524ms Jan 12 06:36:51 volumio-sever volumio[831]: info: ------------------------------ 523ms Jan 12 06:36:51 volumio-sever volumio[831]: info: ------------------------------ 523ms Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume94 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume94 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume93 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume94 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume93 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume94 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume92 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume93 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:51 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume92 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:51 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:51 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume91 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume92 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume91 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume91 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume90 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume90 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume90 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume89 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume88 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume89 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume88 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume87 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:52 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:52 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:52 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume89 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume88 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume87 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume87 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume88 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume87 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume85 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:53 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume85 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:53 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:53 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:54 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume88 Jan 12 06:36:54 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:54 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:54 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:54 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:55 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume87 Jan 12 06:36:55 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:55 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:55 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:55 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume86 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume85 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume84 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume83 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume82 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume81 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume80 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume79 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume78 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume77 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume77 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume76 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:56 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:56 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:56 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume76 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:57 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume75 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:57 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume74 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:57 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume73 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:57 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume75 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:36:57 volumio-sever volumio[831]: info: VolumeController::SetAlsaVolume74 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreStateMachine::pushState Jan 12 06:36:57 volumio-sever volumio[831]: info: CorePlayQueue::getTrack 4 Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 06:36:57 volumio-sever volumio[831]: info: CoreCommandRouter::volumioPushState Jan 12 06:37:02 volumio-sever kernel: hwmon hwmon1: Voltage normalised Jan 12 06:37:20 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 06:37:20 volumio-sever volumio[831]: info: CURURI: albums:// Jan 12 06:37:20 volumio-sever volumio[831]: info: listAlbums - loading Albums from cache Jan 12 06:37:20 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:37:20 volumio-sever kernel: hwmon hwmon1: Undervoltage detected! Jan 12 06:37:21 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 06:37:21 volumio-sever volumio[831]: info: CURURI: albums://FusionDsp/Test%20Signal Jan 12 06:37:21 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:37:21 volumio-sever volumio[831]: info: No valid Plugin REST Endpoint Jan 12 06:37:21 volumio-sever volumio[831]: info: No valid Plugin REST Endpoint Jan 12 06:37:27 volumio-sever kernel: hwmon hwmon1: Voltage normalised Jan 12 06:37:32 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 06:37:32 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:37:33 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 06:37:34 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:37:35 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 06:37:36 volumio-sever volumio[831]: info: Preload queue cleared Jan 12 06:37:37 volumio-sever kernel: hwmon hwmon1: Undervoltage detected! Jan 12 06:37:38 volumio-sever volumio[831]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 06:37:38 volumio-sever volumio[831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 06:37:38 volumio-sever volumio[831]: TypeError: Cannot read property 'length' of undefined Jan 12 06:37:38 volumio-sever volumio[831]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 12 06:37:38 volumio-sever volumio[831]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 12 06:37:38 volumio-sever volumio[831]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 12 06:37:38 volumio-sever volumio[831]: at Parser.emit (events.js:315:20) Jan 12 06:37:38 volumio-sever volumio[831]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 12 06:37:38 volumio-sever volumio[831]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 12 06:37:38 volumio-sever volumio[831]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 12 06:37:38 volumio-sever volumio[831]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 12 06:37:38 volumio-sever volumio[831]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 12 06:37:38 volumio-sever volumio[831]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 12 06:37:38 volumio-sever volumio[831]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 12 06:37:38 volumio-sever volumio[831]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 12 06:37:38 volumio-sever volumio[831]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 12 06:37:38 volumio-sever volumio[831]: at IncomingMessage.emit (events.js:327:22) Jan 12 06:37:38 volumio-sever volumio[831]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 12 06:37:38 volumio-sever volumio[831]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 12 06:37:38 volumio-sever volumio[831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 06:37:39 volumio-sever sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 06:36 Jan 12 06:37:39 volumio-sever sudo[1821]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"