-- 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"