-- Logs begin at Mon 2025-04-21 18:34:08 CST, end at Mon 2025-04-21 18:38:57 CST. -- Apr 21 18:37:01 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 18:37:01 volumio volumio[1305]: info: CURURI: music-library/USB/dsn/1 Apr 21 18:37:01 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:02 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::ClearQueue Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::serviceStop Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::serviceStop Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::stop Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::clearPlayQueue Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::addQueueItems Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::addQueueItems Apr 21 18:37:02 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:02 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff Apr 21 18:37:02 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPlay Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::play index 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::play index undefined Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::startPlaybackTimer Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::clearAddPlayTracks USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand stop took 17 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand stop took 4 milliseconds Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:02 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand status took 1 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand clear took 2 milliseconds Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff" Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:02 volumio volumio[1305]: error: updateQueue error: null Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:02 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 4ms Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand add "USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff" took 4 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand status took 2 milliseconds Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand play Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:02 volumio volumio[1305]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 21 18:37:02 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState stateService stop Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: No code Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 19ms Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 31ms Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:02 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 32ms Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand play took 30 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 3ms Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:02 volumio volumio[1305]: info: Apr 21 18:37:02 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand status took 5 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand status took 4 milliseconds Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 21 18:37:02 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 21 18:37:02 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:02 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":375,"duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:02 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:02 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:02 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:02 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:02 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:02 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 30ms Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 99ms Apr 21 18:37:02 volumio volumio[1305]: info: ------------------------------ 98ms Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioSeek Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::seek Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: TRACKBLOCK {"uri":"mnt/USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","service":"mpd","name":"01 (Side-A). Adele - Strangers By Nature.dff","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=910&web=//extralarge&path=%2FUSB%2Fdsn%2F1&metadata=false","duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","trackType":"dff","channels":2} Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::startPlaybackTimer Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: ControllerMpd::seek Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:04 volumio volumio[1305]: error: null Apr 21 18:37:04 volumio volumio[1305]: info: Apr 21 18:37:04 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:04 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:04 volumio volumio[1305]: info: Apr 21 18:37:04 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:04 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:04 volumio volumio[1305]: info: sendMpdCommand status took 2 milliseconds Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:04 volumio volumio[1305]: info: sendMpdCommand status took 2 milliseconds Apr 21 18:37:04 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:04 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60000,"duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:04 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:04 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:04 volumio volumio[1305]: info: ------------------------------ 19ms Apr 21 18:37:04 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 21 18:37:04 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:04 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60000,"duration":179,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:04 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:04 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:04 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:04 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:04 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:04 volumio volumio[1305]: info: ------------------------------ 33ms Apr 21 18:37:16 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::ClearQueue Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::serviceStop Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::serviceStop Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::stop Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::clearPlayQueue Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::addQueueItems Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::addQueueItems Apr 21 18:37:16 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:16 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:37:16 volumio volumio[1305]: info: Exploding uri music-library/USB/dsn/01.Serenade D. 957 No. 4.flac in service mpd Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=Sung%20Min%20Je%2CHyun%20Ho%20Choi/I%20LOVE%20CONTRABASS/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F01.Serenade%20D.%20957%20No.%204.flac&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand stop took 55 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPlay Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::play index 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::addQueueItems Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::addQueueItems Apr 21 18:37:16 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:37:16 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/1 Apr 21 18:37:16 volumio volumio[1305]: info: Exploding uri music-library/USB/dsn/1 in service mpd Apr 21 18:37:16 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/2 Apr 21 18:37:16 volumio volumio[1305]: info: Exploding uri music-library/USB/dsn/2 in service mpd Apr 21 18:37:16 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/天国的女儿（开盘母带1：1直刻版） Apr 21 18:37:16 volumio volumio[1305]: info: Exploding uri music-library/USB/dsn/天国的女儿（开盘母带1：1直刻版） in service mpd Apr 21 18:37:16 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/$RECYCLE.BIN Apr 21 18:37:16 volumio volumio[1305]: info: Exploding uri music-library/USB/dsn/$RECYCLE.BIN in service mpd Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::play index undefined Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::startPlaybackTimer Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::clearAddPlayTracks USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=//extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F1%2F01%20(Side-A).%20Adele%20-%20Strangers%20By%20Nature.dff&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=Kit%20Chan//extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F2%2F06%20-%20Kit%20Chan%20-%20yue%20wen%20yue%20shang%20xin.dsf&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/2/06 - Kit Chan - yue wen yue shang xin.dsf Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F01.%20Daughter%20of%20heaven%20%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/01. Daughter of heaven 天国的女儿.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F02.%E6%B5%81%E6%B5%AA%E8%80%85%E4%B9%8B%E6%AD%8C.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/02.流浪者之歌.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F03.%20%E7%8C%AE%E7%BB%99%E6%B5%B7%E6%B4%8B.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/03. 献给海洋.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F04.%20Moderato.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/04. Moderato.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F05.%20Lento.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/05. Lento.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F06.%20%20Un%20poco%20piu%20lento.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/06. Un poco piu lento.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F07.%20Allegro%20molto%20vivace.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/07. Allegro molto vivace.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F08.%20%20Fantaisie%20de%20Concert%20sur%20desmotifs%20de%20I'opera%20%E5%8D%A1%E9%97%A8%E5%B9%BB%E6%83%B3%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/08. Fantaisie de Concert sur desmotifs de I'opera 卡门幻想曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F09.%20%20Fantaisie%20de%20Concert%20sur%20desmotifs%20de%20I'opera%20%E5%8D%A1%E9%97%A8%E5%B9%BB%E6%83%B3%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/09. Fantaisie de Concert sur desmotifs de I'opera 卡门幻想曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F10.%20%20Fantaisie%20de%20Concert%20sur%20desmotifs%20de%20I'opera%20%E5%8D%A1%E9%97%A8%E5%B9%BB%E6%83%B3%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/10. Fantaisie de Concert sur desmotifs de I'opera 卡门幻想曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F11.%20%20Fantaisie%20de%20Concert%20sur%20desmotifs%20de%20I'opera%20%E5%8D%A1%E9%97%A8%E5%B9%BB%E6%83%B3%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/11. Fantaisie de Concert sur desmotifs de I'opera 卡门幻想曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F12.%20Fantaisie%20de%20Concert%20sur%20desmotifs%20de%20I'opera%20%E5%8D%A1%E9%97%A8%E5%B9%BB%E6%83%B3%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/12. Fantaisie de Concert sur desmotifs de I'opera 卡门幻想曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: ALBUMART /albumart?cacheid=910&web=%3F%3F%3F%3F%3F/%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fdsn%2F%E5%A4%A9%E5%9B%BD%E7%9A%84%E5%A5%B3%E5%84%BF%EF%BC%88%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A61%EF%BC%9A1%E7%9B%B4%E5%88%BB%E7%89%88%EF%BC%89%2F13.%20Couclusion%20tune%20%E5%A4%A9%E9%81%93%E7%89%87%E5%B0%BE%E6%9B%B2.wav&metadata=false Apr 21 18:37:16 volumio volumio[1305]: info: URI /mnt/USB/dsn/天国的女儿（开盘母带1：1直刻版）/13. Couclusion tune 天道片尾曲.wav Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 28 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand stop took 6 milliseconds Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:16 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand clear took 11 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 5 milliseconds Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "USB/dsn/01.Serenade D. 957 No. 4.flac" Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: 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":"01 (Side-A). Adele - Strangers By Nature.dff","artist":null,"album":null,"uri":"USB/dsn/1/01 (Side-A). Adele - Strangers By Nature.dff","trackType":"dff"} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: No code Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: No code Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 79ms Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 43ms Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:16 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:16 volumio volumio[1305]: error: updateQueue error: null Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:16 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 42ms Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand add "USB/dsn/01.Serenade D. 957 No. 4.flac" took 41 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 3ms Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand play Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:37:16 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 11ms Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand play took 10 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 9ms Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 6 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 4 milliseconds Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:16 volumio volumio[1305]: info: Apr 21 18:37:16 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 8 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand status took 4 milliseconds Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:16 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 31ms Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 40ms Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 72 milliseconds Apr 21 18:37:16 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 71 milliseconds Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:16 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:16 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:16 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:37:16 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:16 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:16 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 116ms Apr 21 18:37:16 volumio volumio[1305]: info: ------------------------------ 112ms Apr 21 18:37:21 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:37:21 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:37:21 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:37:21 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:37:21 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:21 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::volumioPause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::pause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::servicePause Apr 21 18:37:47 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::servicePause Apr 21 18:37:47 volumio volumio[1305]: info: ControllerMpd::pause Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand pause Apr 21 18:37:47 volumio volumio[1305]: info: Apr 21 18:37:47 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:47 volumio volumio[1305]: info: sendMpdCommand pause took 3 milliseconds Apr 21 18:37:47 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:47 volumio volumio[1305]: info: Apr 21 18:37:47 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:37:47 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:37:47 volumio volumio[1305]: info: sendMpdCommand status took 1 milliseconds Apr 21 18:37:47 volumio volumio[1305]: info: sendMpdCommand status took 1 milliseconds Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:37:47 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 21 18:37:47 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:47 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:37:47 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:47 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:47 volumio volumio[1305]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32368,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2503 Kbps","isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:47 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::syncState stateService pause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus pause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:47 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:37:47 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:37:47 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:47 volumio volumio[1305]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32368,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2503 Kbps","isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:37:47 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::syncState stateService pause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus pause Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:37:47 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:47 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:37:47 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:37:47 volumio volumio[1305]: info: ------------------------------ 19ms Apr 21 18:37:47 volumio volumio[1305]: info: ------------------------------ 19ms Apr 21 18:37:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:37:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:37:51 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:37:51 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:37:51 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:37:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:38:02 volumio kernel: usb 1-2.1: USB disconnect, device number 3 Apr 21 18:38:02 volumio thd[795]: Error reading device '/dev/input/event3' Apr 21 18:38:02 volumio volumio[1305]: info: No valid Plugin REST Endpoint Apr 21 18:38:02 volumio volumio[1305]: info: Apr 21 18:38:02 volumio volumio[1305]: ---------------------------- USB Audio Device Detached Apr 21 18:38:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 21 18:38:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:02 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 21 18:38:17 volumio kernel: usb 1-2.1: new high-speed USB device number 4 using xhci_hcd Apr 21 18:38:17 volumio kernel: usb 1-2.1: new high-speed USB device number 5 using xhci_hcd Apr 21 18:38:17 volumio kernel: usb 1-2-port1: attempt power cycle Apr 21 18:38:18 volumio kernel: usb 1-2.1: new full-speed USB device number 6 using xhci_hcd Apr 21 18:38:18 volumio kernel: usb 1-2.1: New USB device found, idVendor=20b1, idProduct=305c, bcdDevice= 0.03 Apr 21 18:38:18 volumio kernel: usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 21 18:38:18 volumio kernel: usb 1-2.1: Product: SABRE HiFi DAC MQA Apr 21 18:38:18 volumio kernel: usb 1-2.1: Manufacturer: VOZA Apr 21 18:38:18 volumio kernel: usbhid 1-2.1:1.2: couldn't find an input interrupt endpoint Apr 21 18:38:18 volumio kernel: input: VOZA SABRE HiFi DAC MQA as /devices/pci0000:00/0000:00:10.0/usb1/1-2/1-2.1/1-2.1:1.3/0003:20B1:305C.0002/input/input8 Apr 21 18:38:18 volumio kernel: hid-generic 0003:20B1:305C.0002: input,hidraw0: USB HID v1.00 Device [VOZA SABRE HiFi DAC MQA] on usb-0000:00:10.0-2.1/input3 Apr 21 18:38:18 volumio kernel: hid-generic 0003:20B1:305C.0003: hiddev0,hidraw1: USB HID v1.00 Device [VOZA SABRE HiFi DAC MQA] on usb-0000:00:10.0-2.1/input4 Apr 21 18:38:18 volumio volumio[1305]: info: Apr 21 18:38:18 volumio volumio[1305]: ---------------------------- USB Audio Device Attached Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::Close All Modals sent Apr 21 18:38:18 volumio volumio[1305]: info: Preparing to save Alsa Options, stopping services first Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:18 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::volumioPause Apr 21 18:38:18 volumio volumio[1305]: info: CoreStateMachine::pause Apr 21 18:38:18 volumio volumio[1305]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SABRE HiFi DAC MQA","alsacard":"MQA"},"i2s":false} Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:18 volumio systemd-udevd[2729]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:18 volumio volumio[1305]: info: Setting mixer PCM for card SABRE HiFi DAC MQA Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 18:38:18 volumio volumio[1305]: info: Updating Volume Controller Parameters: Device: 5 Name: SABRE HiFi DAC MQA Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 21 18:38:18 volumio volumio[1305]: info: Disabling external Volume Control Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 18:38:18 volumio volumio[1305]: info: Preparing to generate the ALSA configuration file Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 18:38:18 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:18 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:18 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:18 volumio volumio[1305]: info: Asound.conf file written Apr 21 18:38:18 volumio sudo[2758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 21 18:38:18 volumio sudo[2758]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio sudo[2758]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio volumio[1305]: info: Output device has changed, restarting MPD Apr 21 18:38:18 volumio sudo[2763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 18:38:18 volumio sudo[2763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio volumio[1305]: info: Output device has changed, restarting Shairport Sync Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:18 volumio sudo[2763]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 18:38:18 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio systemd[1]: Stopping Music Player Daemon... Apr 21 18:38:18 volumio volumio[1305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 18:38:18 volumio volumio[1305]: info: Output device has changed, restarting MPD Apr 21 18:38:18 volumio systemd[1]: mpd.service: Succeeded. Apr 21 18:38:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 21 18:38:18 volumio sudo[2773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 18:38:18 volumio sudo[2773]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio systemd[1]: Starting Music Player Daemon... Apr 21 18:38:18 volumio volumio[1305]: info: Output device has changed, restarting Shairport Sync Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:18 volumio sudo[2773]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 18:38:18 volumio sudo[2776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 21 18:38:18 volumio systemd[1]: mpd.service: Succeeded. Apr 21 18:38:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 21 18:38:18 volumio systemd[1]: Starting Music Player Daemon... Apr 21 18:38:18 volumio volumio[1305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 18:38:18 volumio volumio[1305]: info: MPD Permissions set Apr 21 18:38:18 volumio volumio[1305]: info: MPD Permissions set Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio sudo[2781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 21 18:38:18 volumio sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio sudo[2781]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:18 volumio volumio[1305]: info: Starting Shairport Sync Apr 21 18:38:18 volumio volumio[1305]: info: Starting Shairport Sync Apr 21 18:38:18 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 18:38:18 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 21 18:38:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 21 18:38:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:18 volumio sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 18:38:18 volumio sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:18 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:18 volumio volumio[1305]: info: Shairport-Sync Started Apr 21 18:38:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 21 18:38:18 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 21 18:38:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 21 18:38:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:18 volumio sudo[2800]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:18 volumio volumio[1305]: info: Shairport-Sync Started Apr 21 18:38:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 21 18:38:19 volumio mpd[2793]: Apr 21 18:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 18:38:19 volumio systemd[1]: Started Music Player Daemon. Apr 21 18:38:19 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:19 volumio sudo[2776]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:19 volumio volumio[1305]: error: MPD error: The expression evaluated to a falsy value: Apr 21 18:38:19 volumio volumio[1305]: assert.ok(self.idling) Apr 21 18:38:19 volumio volumio[1305]: error: The expression evaluated to a falsy value: Apr 21 18:38:19 volumio volumio[1305]: assert.ok(self.idling) Apr 21 18:38:19 volumio volumio[1305]: error: updateQueue error: null Apr 21 18:38:22 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 21 18:38:22 volumio volumio[1305]: info: CoreStateMachine::getcurrentVolume Apr 21 18:38:22 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 18:38:22 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:22 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:22 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:22 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:22 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:38:22 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:38:22 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 18:38:22 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:22 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:22 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:22 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:38:25 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:25 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:38:25 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:25 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:38:25 volumio volumio[1305]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kit%20Chan/af5060f8-a307-4ad6-9578-3c0bcf6a1a19.jpg' Apr 21 18:38:26 volumio volumio[1305]: verbose: New Socket.io Connection to 192.168.124.10:3000 from 192.168.124.8 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Apr 21 18:38:26 volumio volumio[1305]: verbose: New Socket.io Connection to 192.168.124.10 from 192.168.124.8 UA: Mozilla/5.0 (Linux; Android 15; PHZ110 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.58 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:26 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 18:38:26 volumio volumio[1305]: info: Received Get System Info Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:38:26 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:26 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:26 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:26 volumio volumio[1305]: info: Listing playlists Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::volumioGetQueue Apr 21 18:38:26 volumio volumio[1305]: info: CoreStateMachine::getQueue Apr 21 18:38:26 volumio volumio[1305]: info: CorePlayQueue::getQueue Apr 21 18:38:26 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 21 18:38:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 18:38:34 volumio volumio[1305]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 21 18:38:34 volumio volumio[1305]: info: Preparing to save Alsa Options, stopping services first Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:34 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::volumioPause Apr 21 18:38:34 volumio volumio[1305]: info: CoreStateMachine::pause Apr 21 18:38:34 volumio volumio[1305]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SABRE HiFi DAC MQA"}} Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:34 volumio volumio[1305]: info: Setting mixer PCM for card SABRE HiFi DAC MQA Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 18:38:34 volumio volumio[1305]: info: Updating Volume Controller Parameters: Device: 5 Name: SABRE HiFi DAC MQA Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 21 18:38:34 volumio volumio[1305]: info: Disabling external Volume Control Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 18:38:34 volumio volumio[1305]: info: Preparing to generate the ALSA configuration file Apr 21 18:38:34 volumio volumio[1305]: info: Asound.conf file unchanged, so no further update is needed Apr 21 18:38:34 volumio volumio[1305]: info: Output device has changed, restarting MPD Apr 21 18:38:34 volumio sudo[2858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 18:38:34 volumio sudo[2858]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:34 volumio volumio[1305]: info: Output device has changed, restarting Shairport Sync Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:34 volumio sudo[2858]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:34 volumio sudo[2861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 18:38:34 volumio sudo[2861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:34 volumio systemd[1]: Stopping Music Player Daemon... Apr 21 18:38:34 volumio volumio[1305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 18:38:34 volumio systemd[1]: mpd.service: Succeeded. Apr 21 18:38:34 volumio systemd[1]: Stopped Music Player Daemon. Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 18:38:34 volumio systemd[1]: Starting Music Player Daemon... Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 18:38:34 volumio volumio[1305]: info: MPD Permissions set Apr 21 18:38:34 volumio sudo[2866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 21 18:38:34 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:34 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:34 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:34 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:34 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 18:38:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 18:38:34 volumio volumio[1305]: info: Starting Shairport Sync Apr 21 18:38:34 volumio sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 18:38:34 volumio sudo[2881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 18:38:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 21 18:38:34 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 21 18:38:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 21 18:38:34 volumio sudo[2881]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:34 volumio volumio[1305]: info: Shairport-Sync Started Apr 21 18:38:35 volumio mpd[2873]: Apr 21 18:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 18:38:35 volumio systemd[1]: Started Music Player Daemon. Apr 21 18:38:35 volumio sudo[2861]: pam_unix(sudo:session): session closed for user root Apr 21 18:38:35 volumio volumio[1305]: error: updateQueue error: null Apr 21 18:38:38 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 21 18:38:38 volumio volumio[1305]: info: CoreStateMachine::getcurrentVolume Apr 21 18:38:38 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 18:38:38 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:38 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:38 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:38 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:38 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:38:38 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:38:38 volumio volumio[1305]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 18:38:38 volumio volumio[1305]: info: VolumeController:: Volume=100 Mute =false Apr 21 18:38:38 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:38 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:38 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:42 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::ClearQueue Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::serviceStop Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::serviceStop Apr 21 18:38:42 volumio volumio[1305]: info: ControllerMpd::stop Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::clearPlayQueue Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::addQueueItems Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::addQueueItems Apr 21 18:38:42 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:38:42 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:38:42 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPlay Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::play index 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::addQueueItems Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::addQueueItems Apr 21 18:38:42 volumio volumio[1305]: info: Preload queue cleared Apr 21 18:38:42 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/1 Apr 21 18:38:42 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/1 Apr 21 18:38:42 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/2 Apr 21 18:38:42 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/2 Apr 21 18:38:42 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/天国的女儿（开盘母带1：1直刻版） Apr 21 18:38:42 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/天国的女儿（开盘母带1：1直刻版） Apr 21 18:38:42 volumio volumio[1305]: info: Adding Item to queue: music-library/USB/dsn/$RECYCLE.BIN Apr 21 18:38:42 volumio volumio[1305]: info: Using cached record of: music-library/USB/dsn/$RECYCLE.BIN Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::stop Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::saveQueue Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::play index undefined Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::startPlaybackTimer Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::clearAddPlayTracks USB/dsn/01.Serenade D. 957 No. 4.flac Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand stop took 27 milliseconds Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand stop took 4 milliseconds Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 18:38:42 volumio volumio[1305]: info: Apr 21 18:38:42 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:38:42 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand clear took 1 milliseconds Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "USB/dsn/01.Serenade D. 957 No. 4.flac" Apr 21 18:38:42 volumio volumio[1305]: error: updateQueue error: null Apr 21 18:38:42 volumio volumio[1305]: info: Apr 21 18:38:42 volumio volumio[1305]: ---------------------------- MPD announces system playlist update Apr 21 18:38:42 volumio volumio[1305]: info: Ignoring MPD Status Update Apr 21 18:38:42 volumio volumio[1305]: info: ------------------------------ 2ms Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand add "USB/dsn/01.Serenade D. 957 No. 4.flac" took 1 milliseconds Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand play Apr 21 18:38:42 volumio volumio[1305]: info: ------------------------------ 1ms Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand play took 1 milliseconds Apr 21 18:38:42 volumio volumio[1305]: info: Apr 21 18:38:42 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:38:42 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:38:42 volumio volumio[1305]: info: Apr 21 18:38:42 volumio volumio[1305]: ---------------------------- MPD announces state update: player Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand status took 84 milliseconds Apr 21 18:38:42 volumio volumio[1305]: info: ControllerMpd::getState Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand status took 2 milliseconds Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::parseState Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:38:42 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1919 Kbps","isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:38:42 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Apr 21 18:38:42 volumio volumio[1305]: info: ------------------------------ 91ms Apr 21 18:38:42 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 21 18:38:42 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo Apr 21 18:38:42 volumio volumio[1305]: info: ControllerMpd::pushState Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::servicePushState Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":274,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1926 Kbps","isStreaming":false,"title":"Serenade D. 957 No. 4","artist":"Sung Min Je,Hyun Ho Choi","album":"I LOVE CONTRABASS","uri":"USB/dsn/01.Serenade D. 957 No. 4.flac","trackType":"flac"} Apr 21 18:38:42 volumio volumio[1305]: verbose: CURRENT POSITION 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play Apr 21 18:38:42 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:42 volumio volumio[1305]: info: CoreStateMachine::pushState Apr 21 18:38:42 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:42 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState Apr 21 18:38:42 volumio volumio[1305]: info: ------------------------------ 22ms Apr 21 18:38:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 18:38:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 18:38:51 volumio volumio[1305]: info: Discovery: Getting this device information Apr 21 18:38:51 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState Apr 21 18:38:51 volumio volumio[1305]: info: CorePlayQueue::getTrack 0 Apr 21 18:38:51 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 18:38:56 volumio volumio[1305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 18:38:56 volumio volumio[1305]: Error: connect ETIMEDOUT 108.160.172.204:80 Apr 21 18:38:56 volumio volumio[1305]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 21 18:38:56 volumio volumio[1305]: errno: -110, Apr 21 18:38:56 volumio volumio[1305]: code: 'ETIMEDOUT', Apr 21 18:38:56 volumio volumio[1305]: syscall: 'connect', Apr 21 18:38:56 volumio volumio[1305]: address: '108.160.172.204', Apr 21 18:38:56 volumio volumio[1305]: port: 80 Apr 21 18:38:56 volumio volumio[1305]: } Apr 21 18:38:56 volumio volumio[1305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 18:38:57 volumio sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-21 18:37 Apr 21 18:38:57 volumio sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"