-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2024-03-31 09:34:54 CEST. -- Mar 31 09:33:14 volumio ntpd[805]: receive: Unexpected origin timestamp 0xe9b39020.f5814193 does not match aorg 0000000000.00000000 from server@95.110.254.234 xmt 0xe9b3903a.036c9b39 Mar 31 09:33:14 volumio ntpd[805]: receive: Unexpected origin timestamp 0xe9b39020.f57f25b6 does not match aorg 0000000000.00000000 from server@37.247.53.178 xmt 0xe9b3903a.03f5b43c Mar 31 09:33:14 volumio ntpd[805]: receive: Unexpected origin timestamp 0xe9b39020.f57e62cd does not match aorg 0000000000.00000000 from server@95.230.240.5 xmt 0xe9b3903a.03deb52c Mar 31 09:33:14 volumio ntpd[805]: receive: Unexpected origin timestamp 0xe9b39020.f57af0c2 does not match aorg 0000000000.00000000 from server@149.62.187.162 xmt 0xe9b3903a.04e259ee Mar 31 09:33:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:14 volumio volumio[883]: info: CURURI: music-library Mar 31 09:33:14 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:15 volumio volumio[883]: info: CURURI: music-library/USB Mar 31 09:33:15 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:15 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:15 volumio volumio[883]: info: CURURI: music-library/USB/LEXAR Mar 31 09:33:15 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/audiocheck.net_pink_176k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/audiocheck.net_pink_192k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/audiocheck.net_pink_88k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/audiocheck.net_pink_96k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/2-Musica Nuda - Libera.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/t3-Pink Noise Decorrelato.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/t4-Pink noise L+R.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/t1-Channel Identification.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/t2-Channel Phasing.wav Mar 31 09:33:15 volumio volumio[883]: info: Preloading song: music-library/USB/LEXAR/t5-1KHZ Harmonic & sub-harmonic distortion (L+R).wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/audiocheck.net_pink_176k_-3dBFS.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=//extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Faudiocheck.net_pink_176k_-3dBFS.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/audiocheck.net_pink_176k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/audiocheck.net_pink_192k_-3dBFS.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=//extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Faudiocheck.net_pink_192k_-3dBFS.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/audiocheck.net_pink_192k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/audiocheck.net_pink_88k_-3dBFS.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=//extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Faudiocheck.net_pink_88k_-3dBFS.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/audiocheck.net_pink_88k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/audiocheck.net_pink_96k_-3dBFS.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=//extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Faudiocheck.net_pink_96k_-3dBFS.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/audiocheck.net_pink_96k_-3dBFS.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/2-Musica Nuda - Libera.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=Musica%20Nuda/Banda%20Larga/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2F2-Musica%20Nuda%20-%20Libera.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/2-Musica Nuda - Libera.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/t3-Pink Noise Decorrelato.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=/Stereophile%20CD2/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Ft3-Pink%20Noise%20Decorrelato.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/t3-Pink Noise Decorrelato.wav Mar 31 09:33:15 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/t4-Pink noise L+R.wav in service mpd Mar 31 09:33:15 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=/Stereophile%20CD2/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Ft4-Pink%20noise%20L%2BR.wav&metadata=false Mar 31 09:33:15 volumio volumio[883]: info: URI /mnt/USB/LEXAR/t4-Pink noise L+R.wav Mar 31 09:33:16 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/t1-Channel Identification.wav in service mpd Mar 31 09:33:16 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=Various/Stereophile%20Test%20CD%202/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Ft1-Channel%20Identification.wav&metadata=false Mar 31 09:33:16 volumio volumio[883]: info: URI /mnt/USB/LEXAR/t1-Channel Identification.wav Mar 31 09:33:16 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/t2-Channel Phasing.wav in service mpd Mar 31 09:33:16 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=Various/Stereophile%20Test%20CD%202/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Ft2-Channel%20Phasing.wav&metadata=false Mar 31 09:33:16 volumio volumio[883]: info: URI /mnt/USB/LEXAR/t2-Channel Phasing.wav Mar 31 09:33:16 volumio volumio[883]: info: Exploding uri music-library/USB/LEXAR/t5-1KHZ Harmonic & sub-harmonic distortion (L+R).wav in service mpd Mar 31 09:33:16 volumio volumio[883]: info: ALBUMART /albumart?cacheid=372&web=Various/Stereophile%20Test%20CD%202/extralarge&path=%2Fmnt%2FUSB%2FLEXAR%2Ft5-1KHZ%20Harmonic%20%26%20sub-harmonic%20distortion%20(L%2BR).wav&metadata=false Mar 31 09:33:16 volumio volumio[883]: info: URI /mnt/USB/LEXAR/t5-1KHZ Harmonic & sub-harmonic distortion (L+R).wav Mar 31 09:33:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:21 volumio volumio[883]: info: CURURI: music-library Mar 31 09:33:21 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:22 volumio volumio[883]: info: CURURI: music-library/USB Mar 31 09:33:22 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:22 volumio volumio[883]: info: CURURI: music-library/USB/LEXAR Mar 31 09:33:22 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 09:33:23 volumio volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 31 09:33:23 volumio volumio[883]: info: Received Get System Version Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 31 09:33:23 volumio volumio[883]: info: Received Get System Info Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 09:33:23 volumio volumio[883]: info: Discovery: Getting this device information Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Mar 31 09:33:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 09:33:26 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::ClearQueue Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::stop Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::clearPlayQueue Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::saveQueue Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::addQueueItems Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::addQueueItems Mar 31 09:33:26 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:26 volumio volumio[883]: info: Adding Item to queue: music-library/USB/LEXAR/t3-Pink Noise Decorrelato.wav Mar 31 09:33:26 volumio volumio[883]: info: Using cached record of: music-library/USB/LEXAR/t3-Pink Noise Decorrelato.wav Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::saveQueue Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::play index 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::stop Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::play index undefined Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::clearAddPlayTracks USB/LEXAR/t3-Pink Noise Decorrelato.wav Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand stop took 2 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand clear took 1 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand add "USB/LEXAR/t3-Pink Noise Decorrelato.wav" Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: error: updateQueue error: null Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 3ms Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand add "USB/LEXAR/t3-Pink Noise Decorrelato.wav" took 2 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 2ms Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 1ms Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces system playlist update Mar 31 09:33:26 volumio volumio[883]: info: Ignoring MPD Status Update Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 2ms Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand play took 1 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 1ms Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 0ms Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 39 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 40 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 40 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 43ms Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: Mar 31 09:33:26 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Mar 31 09:33:26 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Mar 31 09:33:26 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 62ms Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 61ms Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 31 09:33:26 volumio volumio[883]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Mar 31 09:33:26 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Mar 31 09:33:26 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:26 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Mar 31 09:33:26 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:26 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:26 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:26 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 44ms Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 42ms Mar 31 09:33:26 volumio volumio[883]: info: ------------------------------ 42ms Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::volumioPause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::servicePause Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::servicePause Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::pause Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand pause Mar 31 09:33:28 volumio volumio[883]: info: Mar 31 09:33:28 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand pause took 0 milliseconds Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:28 volumio volumio[883]: info: Mar 31 09:33:28 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:28 volumio volumio[883]: info: Mar 31 09:33:28 volumio volumio[883]: ---------------------------- MPD announces state update: player Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 31 09:33:28 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:28 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:28 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:28 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:33:28 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:33:28 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:33:28 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:28 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:33:28 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:33:28 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:33:28 volumio volumio[883]: info: ------------------------------ 19ms Mar 31 09:33:28 volumio volumio[883]: info: ------------------------------ 18ms Mar 31 09:33:28 volumio volumio[883]: info: ------------------------------ 17ms Mar 31 09:33:31 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 09:33:31 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 09:33:31 volumio volumio[883]: info: Discovery: Getting this device information Mar 31 09:33:31 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Mar 31 09:33:31 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:33:31 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 09:33:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 09:33:33 volumio volumio[883]: info: CURURI: music-library/USB Mar 31 09:33:33 volumio volumio[883]: info: Preload queue cleared Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: Retrieving Cloud Streaming UI Mar 31 09:33:41 volumio volumio[883]: info: Getting Tidal Cloud Configuration Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: Getting Qobuz Cloud Configuration Mar 31 09:33:41 volumio volumio[883]: info: Asking plugin for UI Config Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: Getting Spotify Cloud Configuration Mar 31 09:33:41 volumio volumio[883]: info: Asking plugin for UI Config Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: Saving Spotify Acccount Mar 31 09:33:41 volumio volumio[883]: info: Got it Mar 31 09:33:41 volumio volumio[883]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 31 09:33:41 volumio volumio[883]: info: Got Tidal Cloud Configuration Mar 31 09:33:41 volumio volumio[883]: info: Got it Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:33:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 31 09:33:43 volumio volumio[883]: info: Disabling MyMusic plugin upnp Mar 31 09:33:43 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 31 09:33:43 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:33:43 volumio volumio[883]: error: Upnp client error: Error: This socket has been ended by the other party Mar 31 09:33:43 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 31 09:33:43 volumio volumio[883]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 09:33:45 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:33:49 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: Retrieving Cloud Streaming UI Mar 31 09:33:54 volumio volumio[883]: info: Getting Tidal Cloud Configuration Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: Getting Qobuz Cloud Configuration Mar 31 09:33:54 volumio volumio[883]: info: Asking plugin for UI Config Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: Getting Spotify Cloud Configuration Mar 31 09:33:54 volumio volumio[883]: info: Asking plugin for UI Config Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: Saving Spotify Acccount Mar 31 09:33:54 volumio volumio[883]: info: Got it Mar 31 09:33:54 volumio volumio[883]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 31 09:33:54 volumio volumio[883]: info: Got Tidal Cloud Configuration Mar 31 09:33:54 volumio volumio[883]: info: Got it Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:33:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 31 09:33:56 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 31 09:33:56 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 31 09:33:58 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:06 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:07 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Mar 31 09:34:07 volumio volumio[883]: info: Adding a new share Mar 31 09:34:07 volumio volumio[883]: info: No correspondence found in configuration for share Multimedia on IP 192.168.1.13 Mar 31 09:34:07 volumio sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Multimedia /mnt/NAS/Multimedia Mar 31 09:34:07 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:34:07 volumio kernel: Key type cifs.spnego registered Mar 31 09:34:07 volumio kernel: Key type cifs.idmap registered Mar 31 09:34:07 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 31 09:34:07 volumio kernel: CIFS: Attempting to mount \\192.168.1.13\Multimedia Mar 31 09:34:07 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Mar 31 09:34:07 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Mar 31 09:34:07 volumio volumio[883]: info: Permission denied for Multimedia on IP 192.168.1.13 Mar 31 09:34:08 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 31 09:34:10 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:26 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:38 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:42 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:46 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 31 09:34:47 volumio volumio[883]: info: Share Multimedia successfully unmounted Mar 31 09:34:47 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=CKxFa00195-3b6-2784,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Multimedia /mnt/NAS/Multimedia Mar 31 09:34:47 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:34:47 volumio kernel: CIFS: Attempting to mount \\192.168.1.13\Multimedia Mar 31 09:34:47 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: info: Mar 31 09:34:47 volumio volumio[883]: ---------------------------- MPD announces state update: update Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: info: Mar 31 09:34:47 volumio volumio[883]: ---------------------------- MPD announces state update: update Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: info: Mar 31 09:34:47 volumio volumio[883]: ---------------------------- MPD announces state update: update Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::getState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Mar 31 09:34:47 volumio volumio[883]: info: [1711870487772] Database update started Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 8 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand status took 8 milliseconds Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: info: Command Router : Notfying DB Updatetrue Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: info: Command Router : Notfying DB Updatetrue Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: info: Command Router : Notfying DB Updatetrue Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseState Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 31 09:34:47 volumio volumio[883]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:34:47 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:34:47 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:34:47 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:34:47 volumio volumio[883]: info: ControllerMpd::pushState Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::servicePushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2974,"duration":41,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise Decorrelato","artist":null,"album":"Stereophile CD2","uri":"USB/LEXAR/t3-Pink Noise Decorrelato.wav","trackType":"wav"} Mar 31 09:34:47 volumio volumio[883]: verbose: CURRENT POSITION 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState stateService pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus pause Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::pushState Mar 31 09:34:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Mar 31 09:34:47 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output update for this device Mar 31 09:34:47 volumio volumio[883]: info: MRS: Pushing multiroomSync output Mar 31 09:34:47 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Mar 31 09:34:47 volumio volumio[883]: info: ------------------------------ 83ms Mar 31 09:34:47 volumio volumio[883]: info: ------------------------------ 82ms Mar 31 09:34:47 volumio volumio[883]: info: ------------------------------ 81ms Mar 31 09:34:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 31 09:34:50 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 09:34:53 volumio volumio[883]: info: Disabling MyMusic plugin upnp Mar 31 09:34:53 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 31 09:34:53 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:34:53 volumio volumio[883]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 09:34:54 volumio volumio[883]: info: Enabling MyMusic plugin upnp Mar 31 09:34:54 volumio volumio[883]: info: Enabling plugin upnp Mar 31 09:34:54 volumio volumio[883]: info: Loading plugin "upnp"... Mar 31 09:34:54 volumio volumio[883]: info: [1711870494071] Starting Upmpd Daemon Mar 31 09:34:54 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 09:34:54 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 09:34:54 volumio volumio[883]: Error: listen EADDRINUSE: address already in use :::6599 Mar 31 09:34:54 volumio volumio[883]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 31 09:34:54 volumio volumio[883]: at listenInCluster (net.js:1366:12) Mar 31 09:34:54 volumio volumio[883]: at Server.listen (net.js:1452:7) Mar 31 09:34:54 volumio volumio[883]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 31 09:34:54 volumio volumio[883]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 31 09:34:54 volumio volumio[883]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 31 09:34:54 volumio volumio[883]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 31 09:34:54 volumio volumio[883]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 31 09:34:54 volumio volumio[883]: code: 'EADDRINUSE', Mar 31 09:34:54 volumio volumio[883]: errno: -98, Mar 31 09:34:54 volumio volumio[883]: syscall: 'listen', Mar 31 09:34:54 volumio volumio[883]: address: '::', Mar 31 09:34:54 volumio volumio[883]: port: 6599 Mar 31 09:34:54 volumio volumio[883]: } Mar 31 09:34:54 volumio volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 09:34:54 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 09:33 Mar 31 09:34:54 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"