-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2026-01-10 18:21:47 CET. -- Jan 10 18:20:18 rivo volumio[3538]: verbose: New Socket.io Connection to 192.168.1.236 from 192.168.1.97 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 10 18:20:18 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:18 rivo volumio[3538]: info: CorePlayQueue::getTrack 3 Jan 10 18:20:18 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 10 18:20:18 rivo volumio[3538]: info: Listing playlists Jan 10 18:20:18 rivo volumio[3538]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 18:20:18 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 18:20:18 rivo volumio[3538]: info: CoreCommandRouter::volumioGetQueue Jan 10 18:20:18 rivo volumio[3538]: info: CoreStateMachine::getQueue Jan 10 18:20:18 rivo volumio[3538]: info: CorePlayQueue::getQueue Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::volumioPlay Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::play index 4 Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::stop Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::stPlaybackTimer Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::updateTrackBlock Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrackBlock Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 3 Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 3 Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::serviceStop Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 3 Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::serviceStop Jan 10 18:20:19 rivo volumio[3538]: info: ControllerMpd::stop Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:20:19 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 10 18:20:19 rivo kernel: spdif_a keep clk continuous Jan 10 18:20:19 rivo kernel: aml_spdif_close Jan 10 18:20:19 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 10 18:20:19 rivo volumio[3538]: info: Jan 10 18:20:19 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand stop took 16 milliseconds Jan 10 18:20:19 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::play index undefined Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::startPlaybackTimer Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand status took 6 milliseconds Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand stop took 4 milliseconds Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 18:20:19 rivo volumio[3538]: info: Jan 10 18:20:19 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:19 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:19 rivo volumio[3538]: info: Jan 10 18:20:19 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:19 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:19 rivo volumio[3538]: info: Jan 10 18:20:19 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:19 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand clear took 7 milliseconds Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand status took 5 milliseconds Jan 10 18:20:19 rivo volumio[3538]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff" Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:19 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:19 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:19 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:19 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:20:19 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04 Avalokiteshvara","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/04 Avalokiteshvara.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: No code Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: No code Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: No code Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 158ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 149ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 148ms Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:20 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:20 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:20 rivo volumio[3538]: error: updateQueue error: null Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:20 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 153ms Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand add "USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff" took 150 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 6ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 5ms Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand play Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:20 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:20:20 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:20:20 rivo kernel: aml_spdif_open Jan 10 18:20:20 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 10 18:20:20 rivo kernel: set normal 512 fs /4 fs Jan 10 18:20:20 rivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 10 18:20:20 rivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 10 18:20:20 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 10 18:20:20 rivo kernel: set normal 512 fs /4 fs Jan 10 18:20:20 rivo kernel: set spdifout clk:24576000, mpll:98304000 Jan 10 18:20:20 rivo kernel: get spdifout clk:24575951, mpll:98303801 Jan 10 18:20:20 rivo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 10 18:20:20 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 48ms Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand play took 46 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 45ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 44ms Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: MCU Signalled Playback Inactive Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 11 milliseconds Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 65 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 64 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 60 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 56 milliseconds Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 74ms Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: Jan 10 18:20:20 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 6 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:20:20 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:20:20 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:20:20 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 164ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 163ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 160ms Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 88 milliseconds Jan 10 18:20:20 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 89 milliseconds Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:20 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":380,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:20:20 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":380,"duration":87,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Beverly's Troubadour Piece ","artist":"Tammittam Percussion Ensemble ","album":null,"uri":"USB/MUSICA/MORSIANI/Tàmmittam Percussion Ensemble/A Homage to Lou Harrison HD Morsiani Live Recordings/05 Beverly's Troubadour Piece.aiff","trackType":"aiff"} Jan 10 18:20:20 rivo volumio[3538]: verbose: CURRENT POSITION 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:20:20 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:20:20 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:20:20 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 157ms Jan 10 18:20:20 rivo volumio[3538]: info: ------------------------------ 156ms Jan 10 18:20:20 rivo volumio[3538]: info: Signalling Playback active due to playback status change Jan 10 18:20:20 rivo volumio[3538]: info: MCU Signalled Playback Active Jan 10 18:20:37 rivo volumio[3538]: info: Executing endpoint metavolumio Jan 10 18:20:37 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 18:20:44 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 18:20:44 rivo volumio[3538]: info: CURURI: artists:// Jan 10 18:20:44 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:01 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 18:21:01 rivo volumio[3538]: info: CURURI: artists://A-Ha Jan 10 18:21:01 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:01 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:01 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/MORSIANI/Various Artists/Best of Bond - 50 Years 007- Disc 1/1-16 The Living Daylights.m4a Jan 10 18:21:01 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/MORSIANI/Various Artists/Best of Bond - 50 Years 007- Disc 1/1-16 The Living Daylights.m4a in service mpd Jan 10 18:21:01 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=A-Ha/Best%20of%20Bond%20-%2050%20Years%20007-%20Disc%201/small&path=%2Fmnt%2FUSB%2FMUSICA%2FMORSIANI%2FVarious%20Artists%2FBest%20of%20Bond%20-%2050%20Years%20007-%20Disc%201%2F1-16%20The%20Living%20Daylights.m4a&metadata=false Jan 10 18:21:01 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/MORSIANI/Various Artists/Best of Bond - 50 Years 007- Disc 1/1-16 The Living Daylights.m4a Jan 10 18:21:01 rivo volumio[3538]: info: Executing endpoint metavolumio Jan 10 18:21:01 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 18:21:08 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 18:21:08 rivo volumio[3538]: info: CURURI: artists://AC%2FDC Jan 10 18:21:08 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:08 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Shake Your Foundations (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - First Blood (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Danger (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Sink the Pink (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Playing with Girls (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Stand Up (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Hell or High Water (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Back in Business (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Preloading song: music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Send for the Man (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: Executing endpoint metavolumio Jan 10 18:21:08 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Fly%20on%20the%20Wall%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Shake Your Foundations (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Shake%20Your%20Foundations%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Shake Your Foundations (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - First Blood (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20First%20Blood%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - First Blood (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Danger (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Danger%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Danger (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Sink the Pink (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Sink%20the%20Pink%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Sink the Pink (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Playing with Girls (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Playing%20with%20Girls%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Playing with Girls (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Stand Up (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Stand%20Up%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Stand Up (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Hell or High Water (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Hell%20or%20High%20Water%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Hell or High Water (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Back in Business (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Back%20in%20Business%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Back in Business (Album Version).wav Jan 10 18:21:08 rivo volumio[3538]: info: Exploding uri music-library/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Send for the Man (Album Version).wav in service mpd Jan 10 18:21:08 rivo volumio[3538]: info: ALBUMART /albumart?cacheid=969&web=AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall/small&path=%2Fmnt%2FUSB%2FMUSICA%2F%40%20CD%20RIP%2FAC_DC%2FAC_DC%20Fly%20on%20the%20Wall%2FAC_DC%20-%20Send%20for%20the%20Man%20(Album%20Version).wav&metadata=false Jan 10 18:21:08 rivo volumio[3538]: info: URI /mnt/USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Send for the Man (Album Version).wav Jan 10 18:21:13 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 18:21:13 rivo volumio[3538]: info: CURURI: artists://AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall Jan 10 18:21:13 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:14 rivo volumio[3538]: info: Executing endpoint metavolumio Jan 10 18:21:14 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 18:21:14 rivo volumio[3538]: info: Executing endpoint metavolumio Jan 10 18:21:14 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 10 18:21:16 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::ClearQueue Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::stPlaybackTimer Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::updateTrackBlock Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrackBlock Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::serviceStop Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 4 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::serviceStop Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::stop Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::clearPlayQueue Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::saveQueue Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::addQueueItems Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::addQueueItems Jan 10 18:21:16 rivo volumio[3538]: info: Preload queue cleared Jan 10 18:21:16 rivo volumio[3538]: info: Adding Item to queue: artists://AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall Jan 10 18:21:16 rivo volumio[3538]: info: Exploding uri artists://AC%2FDC/AC%2FDC%20Fly%20on%20the%20Wall in service mpd Jan 10 18:21:16 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 10 18:21:16 rivo kernel: spdif_a keep clk continuous Jan 10 18:21:16 rivo kernel: aml_spdif_close Jan 10 18:21:16 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::saveQueue Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::updateTrackBlock Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrackBlock Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPlay Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::play index 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::play index undefined Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::startPlaybackTimer Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand stop took 117 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand stop took 3 milliseconds Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 15 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand clear took 13 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 11 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 9 milliseconds Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav" Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: No code Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: No code Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 112ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 111ms Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo volumio[3538]: info: MCU Signalled Playback Inactive Jan 10 18:21:16 rivo volumio[3538]: error: updateQueue error: null Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 115ms Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 111 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand add "USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav" took 111 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 10ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 10ms Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand play Jan 10 18:21:16 rivo volumio[3538]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 134ms Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces system playlist update Jan 10 18:21:16 rivo volumio[3538]: info: Ignoring MPD Status Update Jan 10 18:21:16 rivo kernel: aml_spdif_open Jan 10 18:21:16 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 10 18:21:16 rivo kernel: set normal 512 fs /4 fs Jan 10 18:21:16 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 10 18:21:16 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 10 18:21:16 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 10 18:21:16 rivo kernel: set normal 512 fs /4 fs Jan 10 18:21:16 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 10 18:21:16 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 10 18:21:16 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 10 18:21:16 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 10 18:21:16 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 58ms Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand play took 55 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 53ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 52ms Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 11 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 8 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 6 milliseconds Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: Jan 10 18:21:16 rivo volumio[3538]: ---------------------------- MPD announces state update: player Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::getState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 15 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 10 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand status took 8 milliseconds Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseState Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:21:16 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:21:16 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 75ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 109ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 107ms Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 101 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 99 milliseconds Jan 10 18:21:16 rivo volumio[3538]: info: sendMpdCommand playlistinfo took 98 milliseconds Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:21:16 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:21:16 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ControllerMpd::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::servicePushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fly on the Wall (Album Version)","artist":"AC/DC","album":"AC/DC Fly on the Wall","uri":"USB/MUSICA/@ CD RIP/AC_DC/AC_DC Fly on the Wall/AC_DC - Fly on the Wall (Album Version).wav","trackType":"wav"} Jan 10 18:21:16 rivo volumio[3538]: verbose: CURRENT POSITION 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState stateService play Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:21:16 rivo volumio[3538]: info: Received an update from plugin. extracting info from payload Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreStateMachine::pushState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioPushState Jan 10 18:21:16 rivo volumio[3538]: info: CoreCommandRouter::volumioGetState Jan 10 18:21:16 rivo volumio[3538]: info: CorePlayQueue::getTrack 0 Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 233ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 225ms Jan 10 18:21:16 rivo volumio[3538]: info: ------------------------------ 224ms Jan 10 18:21:16 rivo volumio[3538]: info: Signalling Playback active due to playback status change Jan 10 18:21:16 rivo volumio[3538]: info: MCU Signalled Playback Active Jan 10 18:21:46 rivo volumio[3538]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 18:21:46 rivo volumio[3538]: TypeError: Cannot read property 'slice' of null Jan 10 18:21:46 rivo volumio[3538]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 10 18:21:46 rivo volumio[3538]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 10 18:21:46 rivo volumio[3538]: at Object.onceWrapper (events.js:520:26) Jan 10 18:21:46 rivo volumio[3538]: at ClientRequest.emit (events.js:400:28) Jan 10 18:21:46 rivo volumio[3538]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Jan 10 18:21:46 rivo volumio[3538]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Jan 10 18:21:46 rivo volumio[3538]: at TLSSocket.socketOnData (_http_client.js:515:22) Jan 10 18:21:46 rivo volumio[3538]: at TLSSocket.emit (events.js:400:28) Jan 10 18:21:46 rivo volumio[3538]: at addChunk (internal/streams/readable.js:293:12) Jan 10 18:21:46 rivo volumio[3538]: at readableAddChunk (internal/streams/readable.js:267:9) Jan 10 18:21:46 rivo volumio[3538]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Jan 10 18:21:46 rivo volumio[3538]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Jan 10 18:21:46 rivo volumio[3538]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 18:21:47 rivo sudo[8699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 18:20 Jan 10 18:21:47 rivo sudo[8699]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 07:07:40 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="90457dc663650a5d2f936402ef6c7dc1"