Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::volumioPlay Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 18:56:02 tawshif volumio[1048]: info: ControllerMpd::resume Oct 16 18:56:02 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:56:02 tawshif volumio[1048]: info: Oct 16 18:56:02 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:02 tawshif volumio[1048]: info: sendMpdCommand play took 22 milliseconds Oct 16 18:56:02 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:02 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:02 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:56:02 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:02 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:02 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:56:02 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:02 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62702,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:02 tawshif volumio[1048]: verbose: CURRENT POSITION 11 Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Oct 16 18:56:02 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:02 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:02 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:02 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:02 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:02 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:02.655+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=62835 volume=100 Oct 16 18:56:02 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:02.656+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:02 tawshif volumio[1048]: info: ------------------------------ 19ms Oct 16 18:56:18 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:18.810+06:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s Oct 16 18:56:18 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:18.821+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.623378ms Oct 16 18:56:18 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:18.863+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=50.225671ms Oct 16 18:56:18 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:18.865+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=52.546669ms Oct 16 18:56:18 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:18.969+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://www.googleapis.com duration=157.386674ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.020+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=http://pushupdates.volumio.org duration=207.72266ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.121+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://securetoken.googleapis.com duration=308.978812ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.122+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=309.234183ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.128+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://functions.volumio.cloud duration=316.077954ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.129+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://functions.volumio.cloud duration=316.426583ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.172+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=http://plugins.volumio.org duration=359.694186ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.186+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://database.volumio.cloud duration=372.696191ms Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.203+06:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=http://cddb.volumio.org duration=391.234301ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.448+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.287342691s timeout=10s endpoint=https://google.com duration=636.865414ms Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","service":"mpd","name":"12 - Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 18:56:19 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.799+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=152000 volume=100 Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.800+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:19 tawshif volumio[1048]: error: null Oct 16 18:56:19 tawshif volumio[1048]: info: Oct 16 18:56:19 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:19 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:19 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:19 tawshif volumio[1048]: info: sendMpdCommand status took 8 milliseconds Oct 16 18:56:19 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:19 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:19 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:56:19 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:19 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":153214,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:19 tawshif volumio[1048]: verbose: CURRENT POSITION 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:19 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:19 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:19 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:19 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.839+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=153214 volume=100 Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.839+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.845+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=153214 volume=100 Oct 16 18:56:19 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:19.846+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:19 tawshif volumio[1048]: info: ------------------------------ 42ms Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","service":"mpd","name":"12 - Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 18:56:22 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.663+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=184000 volume=100 Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.664+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:22 tawshif volumio[1048]: error: null Oct 16 18:56:22 tawshif volumio[1048]: info: Oct 16 18:56:22 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:22 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:22 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:22 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:56:22 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:22 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:22 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 18:56:22 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:22 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184000,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:22 tawshif volumio[1048]: verbose: CURRENT POSITION 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:22 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:22 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:22 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:22 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:22 tawshif volumio[1048]: info: ------------------------------ 16ms Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.902+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=184000 volume=100 Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.902+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.902+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=184000 volume=100 Oct 16 18:56:22 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:22.902+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioNext Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::next Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 11 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:56:23 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:56:23 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.623+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.623+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav" title="12 - Nao Chariya De" Oct 16 18:56:23 tawshif volumio[1048]: info: Oct 16 18:56:23 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:23 tawshif volumio[1048]: info: sendMpdCommand stop took 45 milliseconds Oct 16 18:56:23 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:23 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 12 Oct 16 18:56:23 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:56:23 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:23 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:23 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 18:56:23 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:23 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:23 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService stop Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: info: No code Oct 16 18:56:23 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:23 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:23 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:23 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.695+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=211 volume=100 Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.696+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.696+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=211 volume=100 Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.696+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.697+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=211 volume=100 Oct 16 18:56:23 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:23.697+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:23 tawshif volumio[1048]: info: ------------------------------ 36ms Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav","service":"mpd","name":"1 - Amay Dhore Rakho","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":281,"trackType":"wav"} Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: error: null Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.500+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=66000 volume=100 Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.500+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:24 tawshif volumio[1048]: info: Oct 16 18:56:24 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:24 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:24 tawshif volumio[1048]: info: Oct 16 18:56:24 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:24 tawshif volumio[1048]: info: sendMpdCommand status took 52 milliseconds Oct 16 18:56:24 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:24 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:24 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:24 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":66000,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:24 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:56:24 tawshif volumio[1048]: info: ------------------------------ 58ms Oct 16 18:56:24 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 16 18:56:24 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:24 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":66458,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:24 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:24 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:24 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:24 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:24 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.828+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=66458 volume=100 Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.828+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.829+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=66458 volume=100 Oct 16 18:56:24 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:24.830+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:24 tawshif volumio[1048]: info: ------------------------------ 36ms Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav","service":"mpd","name":"1 - Amay Dhore Rakho","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.439+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=117000 volume=100 Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.440+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:26 tawshif volumio[1048]: error: null Oct 16 18:56:26 tawshif volumio[1048]: info: Oct 16 18:56:26 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:26 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:26 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:26 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:56:26 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:26 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:26 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 18:56:26 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:26 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":118214,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:26 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:26 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:26 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:26 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:26 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.478+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=118214 volume=100 Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.479+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.482+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=118214 volume=100 Oct 16 18:56:26 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:26.482+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:26 tawshif volumio[1048]: info: ------------------------------ 37ms Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav","service":"mpd","name":"1 - Amay Dhore Rakho","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.492+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=163000 volume=100 Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.494+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:27 tawshif volumio[1048]: error: null Oct 16 18:56:27 tawshif volumio[1048]: info: Oct 16 18:56:27 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:27 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:27 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:27 tawshif volumio[1048]: info: sendMpdCommand status took 18 milliseconds Oct 16 18:56:27 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:27 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:27 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:56:27 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:27 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164214,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:27 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:27 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:27 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:27 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:27 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.541+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=164214 volume=100 Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.544+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=164214 volume=100 Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.544+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:27 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:27.544+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:27 tawshif volumio[1048]: info: ------------------------------ 45ms Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioNext Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::next Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:56:28 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.885+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.885+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/01 - Amay Dhore Rakho.wav" title="1 - Amay Dhore Rakho" Oct 16 18:56:28 tawshif volumio[1048]: info: Oct 16 18:56:28 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand stop took 32 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand status took 4 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand stop took 2 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:56:28 tawshif volumio[1048]: info: Oct 16 18:56:28 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:56:28 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand clear took 2 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" Oct 16 18:56:28 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nao Chariya De","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/12 - Nao Chariya De.wav","trackType":"wav"} Oct 16 18:56:28 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService stop Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: No code Oct 16 18:56:28 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:28 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:28 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:28 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.942+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.942+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.943+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.943+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.944+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:28 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:28.944+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:28 tawshif volumio[1048]: info: ------------------------------ 33ms Oct 16 18:56:28 tawshif volumio[1048]: error: updateQueue error: null Oct 16 18:56:28 tawshif volumio[1048]: info: Oct 16 18:56:28 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:56:28 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:56:28 tawshif volumio[1048]: info: ------------------------------ 28ms Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand add "NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" took 26 milliseconds Oct 16 18:56:28 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:56:28 tawshif volumio[1048]: info: ------------------------------ 20ms Oct 16 18:56:28 tawshif volumio[1048]: info: sendMpdCommand play took 20 milliseconds Oct 16 18:56:29 tawshif volumio[1048]: info: Oct 16 18:56:29 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:29 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:29 tawshif volumio[1048]: info: Oct 16 18:56:29 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:29 tawshif volumio[1048]: info: sendMpdCommand status took 20 milliseconds Oct 16 18:56:29 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:29 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:56:29 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:29 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Lalpahari","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav","trackType":"wav"} Oct 16 18:56:29 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:56:29 tawshif volumio[1048]: info: ------------------------------ 24ms Oct 16 18:56:29 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:56:29 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:29 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Lalpahari","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav","trackType":"wav"} Oct 16 18:56:29 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:29 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:29 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:29 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:29 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:29 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:29 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:29 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:29 tawshif volumio[1048]: info: ------------------------------ 19ms Oct 16 18:56:29 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:29.184+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=91 volume=100 Oct 16 18:56:29 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:29.184+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:29 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:29.185+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=91 volume=100 Oct 16 18:56:29 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:29.192+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav","service":"mpd","name":"2 - Lalpahari","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Arnob/Arnob%20%26%20Friends%20Live%20Songs%20From%20World%20Tour%20'08/extralarge&path=%2FNAS%2FDSD-2%2FArnob%20%26%20Friends%20Live%20-%20Songs%20from%20World%20Tour%20'08&metadata=false","duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.680+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=72000 volume=100 Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.683+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:30 tawshif volumio[1048]: error: null Oct 16 18:56:30 tawshif volumio[1048]: info: Oct 16 18:56:30 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:30 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:30 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:30 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:56:30 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:30 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:30 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:56:30 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:30 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73214,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Lalpahari","artist":"Arnob","album":"Arnob & Friends Live Songs From World Tour '08","uri":"NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav","trackType":"wav"} Oct 16 18:56:30 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:30 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.720+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=73214 volume=100 Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.721+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=73214 volume=100 Oct 16 18:56:30 tawshif volumio[1048]: info: ------------------------------ 32ms Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.722+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:30.722+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:34 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 18:56:34 tawshif volumio[1048]: info: CURURI: music-library/NAS/DSD-2/Chris Stapleton Oct 16 18:56:34 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:56:37 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 18:56:37 tawshif volumio[1048]: info: CURURI: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz] Oct 16 18:56:37 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/05. The Fire.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/06. Think I'm In Love With You.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/07. Loving You On My Mind.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/08. White Horse.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/09. Higher.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/10. The Bottom.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/11. The Day I Die.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/12. Crosswind.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/13. Weight Of Your World.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Preloading song: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/14. Mountains Of My Mind.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F01.%20What%20Am%20I%20Gonna%20Do.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F02.%20South%20Dakota.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F03.%20Trust.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F04.%20It%20Takes%20A%20Woman.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/05. The Fire.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F05.%20The%20Fire.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/05. The Fire.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/06. Think I'm In Love With You.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F06.%20Think%20I'm%20In%20Love%20With%20You.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/06. Think I'm In Love With You.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/07. Loving You On My Mind.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F07.%20Loving%20You%20On%20My%20Mind.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/07. Loving You On My Mind.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/08. White Horse.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F08.%20White%20Horse.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/08. White Horse.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/09. Higher.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F09.%20Higher.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/09. Higher.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/10. The Bottom.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F10.%20The%20Bottom.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/10. The Bottom.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/11. The Day I Die.flac in service mpd Oct 16 18:56:37 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F11.%20The%20Day%20I%20Die.flac&metadata=false Oct 16 18:56:37 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/11. The Day I Die.flac Oct 16 18:56:37 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/12. Crosswind.flac in service mpd Oct 16 18:56:38 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F12.%20Crosswind.flac&metadata=false Oct 16 18:56:38 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/12. Crosswind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/13. Weight Of Your World.flac in service mpd Oct 16 18:56:38 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F13.%20Weight%20Of%20Your%20World.flac&metadata=false Oct 16 18:56:38 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/13. Weight Of Your World.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/14. Mountains Of My Mind.flac in service mpd Oct 16 18:56:38 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D%2F14.%20Mountains%20Of%20My%20Mind.flac&metadata=false Oct 16 18:56:38 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/14. Mountains Of My Mind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::ClearQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::clearPlayQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::saveQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::addQueueItems Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::addQueueItems Oct 16 18:56:38 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.565+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.566+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Arnob & Friends Live - Songs from World Tour '08/02 - Lalpahari.wav" title="2 - Lalpahari" Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::saveQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPlay Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::play index 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::addQueueItems Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::addQueueItems Oct 16 18:56:38 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/05. The Fire.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/05. The Fire.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/06. Think I'm In Love With You.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/06. Think I'm In Love With You.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/07. Loving You On My Mind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/07. Loving You On My Mind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/08. White Horse.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/08. White Horse.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/09. Higher.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/09. Higher.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/10. The Bottom.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/10. The Bottom.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/11. The Day I Die.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/11. The Day I Die.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/12. Crosswind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/12. Crosswind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/13. Weight Of Your World.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/13. Weight Of Your World.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/14. Mountains Of My Mind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: Using cached record of: music-library/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/14. Mountains Of My Mind.flac Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::saveQueue Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:56:38 tawshif volumio[1048]: info: Oct 16 18:56:38 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand stop took 42 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand stop took 12 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:56:38 tawshif volumio[1048]: info: Oct 16 18:56:38 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:56:38 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand clear took 2 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" Oct 16 18:56:38 tawshif volumio[1048]: error: updateQueue error: null Oct 16 18:56:38 tawshif volumio[1048]: info: Oct 16 18:56:38 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:56:38 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:56:38 tawshif volumio[1048]: info: ------------------------------ 13ms Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" took 12 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:56:38 tawshif volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 18:56:38 tawshif volumio[1048]: info: ------------------------------ 19ms Oct 16 18:56:38 tawshif volumio[1048]: info: ------------------------------ 10ms Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand play took 8 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: Oct 16 18:56:38 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:38 tawshif volumio[1048]: info: Oct 16 18:56:38 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand status took 82 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand status took 4 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:56:38 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:56:38 tawshif volumio[1048]: info: ------------------------------ 91ms Oct 16 18:56:38 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 16 18:56:38 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:38 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1241,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2292 Kbps","isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:56:38 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:38 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:38 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:38 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:38 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.807+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=1241 volume=100 Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.807+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.807+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=1241 volume=100 Oct 16 18:56:38 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:38.807+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:56:38 tawshif volumio[1048]: info: ------------------------------ 37ms Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","service":"mpd","name":"1 - What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.186+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=49000 volume=100 Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.186+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:56:40 tawshif volumio[1048]: error: null Oct 16 18:56:40 tawshif volumio[1048]: info: Oct 16 18:56:40 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:56:40 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:56:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:56:40 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:56:40 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:56:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:56:40 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:56:40 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:56:40 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49000,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2904 Kbps","isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:56:40 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:56:40 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:56:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:56:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:56:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.332+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=49000 volume=100 Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.332+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=49000 volume=100 Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.332+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:56:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:40.333+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:56:40 tawshif volumio[1048]: info: ------------------------------ 14ms Oct 16 18:56:45 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:45.935+06:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s Oct 16 18:56:45 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:45.947+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=11.380701ms Oct 16 18:56:45 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:45.989+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=52.692238ms Oct 16 18:56:45 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:45.991+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=54.900918ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.097+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://www.googleapis.com duration=160.379298ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.112+06:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://database.volumio.cloud duration=175.316271ms error="Head \"https://database.volumio.cloud\": read tcp 192.168.50.154:58932->104.21.41.5:443: read: connection reset by peer" Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.145+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=http://pushupdates.volumio.org duration=209.331708ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.245+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=309.135246ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.249+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://functions.volumio.cloud duration=312.887961ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.258+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://securetoken.googleapis.com duration=321.585667ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.261+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://functions.volumio.cloud duration=324.302403ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.295+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=http://plugins.volumio.org duration=358.585136ms Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.319+06:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=http://cddb.volumio.org duration=383.483498ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:56:46 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:56:46.575+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.285809182s timeout=10s endpoint=https://google.com duration=639.012433ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.213+06:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.224+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=11.425138ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.266+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=52.105687ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.268+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=55.19342ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.376+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://www.googleapis.com duration=162.185767ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.423+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=http://pushupdates.volumio.org duration=209.470891ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.516+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://functions.volumio.cloud duration=302.647513ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.523+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=309.830347ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.594+06:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=http://cddb.volumio.org duration=380.822098ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.637+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=http://plugins.volumio.org duration=422.540162ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.701+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://functions.volumio.cloud duration=487.364595ms Oct 16 18:57:09 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:09.846+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://google.com duration=632.888069ms Oct 16 18:57:10 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:10.044+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://securetoken.googleapis.com duration=830.720229ms Oct 16 18:57:10 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:10.871+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.267036784s timeout=10s endpoint=https://database.volumio.cloud duration=1.656486245s Oct 16 18:57:11 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:11.996+06:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.010+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=13.814317ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.051+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=55.265396ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.053+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=52.509441ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.163+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://www.googleapis.com duration=158.124635ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.208+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=http://pushupdates.volumio.org duration=211.609851ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.295+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://functions.volumio.cloud duration=296.368302ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.303+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://functions.volumio.cloud duration=300.128311ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.305+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=307.330329ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.324+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://securetoken.googleapis.com duration=327.335538ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.347+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://database.volumio.cloud duration=343.245294ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.382+06:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=http://cddb.volumio.org duration=385.436076ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.397+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=http://plugins.volumio.org duration=394.864441ms Oct 16 18:57:12 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:12.640+06:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.115:47704 @ 0x29525d0" latency=1.266282678s timeout=10s endpoint=https://google.com duration=632.066578ms Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","service":"mpd","name":"1 - What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.510+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=124000 volume=100 Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.510+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:13 tawshif volumio[1048]: error: null Oct 16 18:57:13 tawshif volumio[1048]: info: Oct 16 18:57:13 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:13 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:13 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:13 tawshif volumio[1048]: info: sendMpdCommand status took 12 milliseconds Oct 16 18:57:13 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:13 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:13 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:13 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:13 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124000,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2896 Kbps","isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:57:13 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:13 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:13 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:13 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:13 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.549+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=124000 volume=100 Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.550+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.551+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=124000 volume=100 Oct 16 18:57:13 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:13.551+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:13 tawshif volumio[1048]: info: ------------------------------ 38ms Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","service":"mpd","name":"1 - What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.686+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=148000 volume=100 Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.687+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:15 tawshif volumio[1048]: error: null Oct 16 18:57:15 tawshif volumio[1048]: info: Oct 16 18:57:15 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:15 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:15 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:15 tawshif volumio[1048]: info: sendMpdCommand status took 9 milliseconds Oct 16 18:57:15 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:15 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:15 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:15 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:15 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148000,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2983 Kbps","isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:57:15 tawshif volumio[1048]: verbose: CURRENT POSITION 0 Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:15 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:15 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:15 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:15 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.719+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=148000 volume=100 Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.719+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.720+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=148000 volume=100 Oct 16 18:57:15 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:15.721+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:15 tawshif volumio[1048]: info: ------------------------------ 33ms Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioNext Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::next Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 0 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.726+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.727+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac" title="1 - What Am I Gonna Do" Oct 16 18:57:16 tawshif volumio[1048]: info: Oct 16 18:57:16 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand stop took 32 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand stop took 2 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:57:16 tawshif volumio[1048]: info: Oct 16 18:57:16 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:16 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand clear took 3 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What Am I Gonna Do","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/01. What Am I Gonna Do.flac","trackType":"flac"} Oct 16 18:57:16 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService stop Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: No code Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.781+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.782+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.782+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.782+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.786+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.786+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:16 tawshif volumio[1048]: info: ------------------------------ 35ms Oct 16 18:57:16 tawshif volumio[1048]: error: updateQueue error: null Oct 16 18:57:16 tawshif volumio[1048]: info: Oct 16 18:57:16 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:16 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:16 tawshif volumio[1048]: info: ------------------------------ 31ms Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" took 29 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:57:16 tawshif volumio[1048]: info: ------------------------------ 17ms Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand play took 16 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: Oct 16 18:57:16 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:16 tawshif volumio[1048]: info: Oct 16 18:57:16 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand status took 25 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3115 Kbps","isStreaming":false,"title":"South Dakota","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac","trackType":"flac"} Oct 16 18:57:16 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:16 tawshif volumio[1048]: info: ------------------------------ 29ms Oct 16 18:57:16 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:16 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:16 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1241,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2383 Kbps","isStreaming":false,"title":"South Dakota","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac","trackType":"flac"} Oct 16 18:57:16 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:16 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:16 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:16 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:16 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.902+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=1241 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.902+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.903+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=1241 volume=100 Oct 16 18:57:16 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:16.903+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:16 tawshif volumio[1048]: info: ------------------------------ 19ms Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac","service":"mpd","name":"2 - South Dakota","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.729+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=94000 volume=100 Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.730+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:17 tawshif volumio[1048]: error: null Oct 16 18:57:17 tawshif volumio[1048]: info: Oct 16 18:57:17 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:17 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:17 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:17 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:57:17 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:17 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:17 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:17 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:17 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94000,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2228 Kbps","isStreaming":false,"title":"South Dakota","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac","trackType":"flac"} Oct 16 18:57:17 tawshif volumio[1048]: verbose: CURRENT POSITION 1 Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:17 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:17 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:17 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:17 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.826+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=94000 volume=100 Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.826+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.828+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=94000 volume=100 Oct 16 18:57:17 tawshif volumio[1048]: info: ------------------------------ 18ms Oct 16 18:57:17 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:17.830+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioNext Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::next Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 1 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.387+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.388+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac" title="2 - South Dakota" Oct 16 18:57:20 tawshif volumio[1048]: info: Oct 16 18:57:20 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand stop took 19 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand stop took 2 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:57:20 tawshif volumio[1048]: info: Oct 16 18:57:20 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:20 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand clear took 5 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Dakota","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/02. South Dakota.flac","trackType":"flac"} Oct 16 18:57:20 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService stop Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: No code Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.436+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.436+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.436+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.437+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.437+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.437+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:20 tawshif volumio[1048]: info: ------------------------------ 37ms Oct 16 18:57:20 tawshif volumio[1048]: error: updateQueue error: null Oct 16 18:57:20 tawshif volumio[1048]: info: Oct 16 18:57:20 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:20 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:20 tawshif volumio[1048]: info: ------------------------------ 33ms Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" took 32 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:57:20 tawshif volumio[1048]: info: ------------------------------ 15ms Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand play took 14 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: Oct 16 18:57:20 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:20 tawshif volumio[1048]: info: Oct 16 18:57:20 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand status took 10 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:20 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:20 tawshif volumio[1048]: info: ------------------------------ 16ms Oct 16 18:57:20 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 18:57:20 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:20 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:20 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:20 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:20 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:20 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:20 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.556+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.556+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.561+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 16 18:57:20 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:20.562+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:20 tawshif volumio[1048]: info: ------------------------------ 39ms Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","service":"mpd","name":"3 - Trust","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.387+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=59000 volume=100 Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.387+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:21 tawshif volumio[1048]: error: null Oct 16 18:57:21 tawshif volumio[1048]: info: Oct 16 18:57:21 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:21 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:21 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:21 tawshif volumio[1048]: info: sendMpdCommand status took 4 milliseconds Oct 16 18:57:21 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:21 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:21 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:21 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:21 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59000,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2697 Kbps","isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:21 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:21 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:21 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:21 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:21 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.484+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=59000 volume=100 Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.485+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.486+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=59000 volume=100 Oct 16 18:57:21 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:21.486+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:21 tawshif volumio[1048]: info: ------------------------------ 32ms Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","service":"mpd","name":"3 - Trust","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.414+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=131000 volume=100 Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.414+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:30 tawshif volumio[1048]: error: null Oct 16 18:57:30 tawshif volumio[1048]: info: Oct 16 18:57:30 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:30 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:30 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:30 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:57:30 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:30 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:30 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:30 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:30 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131000,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2497 Kbps","isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:30 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:30 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:30 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:30 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:30 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.457+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=131000 volume=100 Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.458+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.458+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=131000 volume=100 Oct 16 18:57:30 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:30.459+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:30 tawshif volumio[1048]: info: ------------------------------ 27ms Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","service":"mpd","name":"3 - Trust","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.634+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=164000 volume=100 Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.634+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:39 tawshif volumio[1048]: error: null Oct 16 18:57:39 tawshif volumio[1048]: info: Oct 16 18:57:39 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:39 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:39 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:39 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:57:39 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:39 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:39 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:39 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:39 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":203,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2521 Kbps","isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:39 tawshif volumio[1048]: verbose: CURRENT POSITION 2 Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:39 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:39 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:39 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:39 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.671+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=164000 volume=100 Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.672+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.673+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=164000 volume=100 Oct 16 18:57:39 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:39.674+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:39 tawshif volumio[1048]: info: ------------------------------ 25ms Oct 16 18:57:39 tawshif systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioNext Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::next Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 2 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.520+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.521+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac" title="3 - Trust" Oct 16 18:57:40 tawshif volumio[1048]: info: Oct 16 18:57:40 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand stop took 10 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::play index undefined Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand status took 2 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand stop took 1 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:57:40 tawshif volumio[1048]: info: Oct 16 18:57:40 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:40 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand clear took 1 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trust","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/03. Trust.flac","trackType":"flac"} Oct 16 18:57:40 tawshif volumio[1048]: verbose: CURRENT POSITION 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService stop Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: No code Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.544+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.545+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.545+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.545+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.545+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:40 tawshif volumio[1048]: info: ------------------------------ 16ms Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.545+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:40 tawshif volumio[1048]: error: updateQueue error: null Oct 16 18:57:40 tawshif volumio[1048]: info: Oct 16 18:57:40 tawshif volumio[1048]: ---------------------------- MPD announces system playlist update Oct 16 18:57:40 tawshif volumio[1048]: info: Ignoring MPD Status Update Oct 16 18:57:40 tawshif volumio[1048]: info: ------------------------------ 14ms Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand add "NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" took 13 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:57:40 tawshif volumio[1048]: info: ------------------------------ 10ms Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand play took 9 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: Oct 16 18:57:40 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:40 tawshif volumio[1048]: info: Oct 16 18:57:40 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand status took 22 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand status took 3 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It Takes A Woman","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","trackType":"flac"} Oct 16 18:57:40 tawshif volumio[1048]: verbose: CURRENT POSITION 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Oct 16 18:57:40 tawshif volumio[1048]: info: ------------------------------ 39ms Oct 16 18:57:40 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:40 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:40 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2010 Kbps","isStreaming":false,"title":"It Takes A Woman","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","trackType":"flac"} Oct 16 18:57:40 tawshif volumio[1048]: verbose: CURRENT POSITION 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:40 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:40 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:40 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:40 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.656+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=483 volume=100 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.657+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.657+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=483 volume=100 Oct 16 18:57:40 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:40.657+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:40 tawshif volumio[1048]: info: ------------------------------ 37ms Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","service":"mpd","name":"4 - It Takes A Woman","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.286+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=63000 volume=100 Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.286+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:41 tawshif volumio[1048]: error: null Oct 16 18:57:41 tawshif volumio[1048]: info: Oct 16 18:57:41 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:41 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:41 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:41 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:57:41 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:41 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:41 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 18:57:41 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:41 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63000,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1997 Kbps","isStreaming":false,"title":"It Takes A Woman","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","trackType":"flac"} Oct 16 18:57:41 tawshif volumio[1048]: verbose: CURRENT POSITION 3 Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:41 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:41 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:41 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:41 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.337+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=63000 volume=100 Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.338+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=63000 volume=100 Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.338+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:41 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:41.338+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:41 tawshif volumio[1048]: info: ------------------------------ 19ms Oct 16 18:57:41 tawshif systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 16 18:57:41 tawshif systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioSeek Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::seek Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: TRACKBLOCK {"uri":"mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","service":"mpd","name":"4 - It Takes A Woman","artist":"Chris Stapleton","album":"Higher","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=683&web=Chris%20Stapleton/Higher/extralarge&path=%2FNAS%2FDSD-2%2FChris%20Stapleton%2F2023%20-%20Higher%20%5B24Bit-96kHz%5D&metadata=false","duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: ControllerMpd::seek Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.766+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=151000 volume=100 Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.766+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:42 tawshif volumio[1048]: error: null Oct 16 18:57:42 tawshif volumio[1048]: info: Oct 16 18:57:42 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:42 tawshif volumio[1048]: info: ControllerMpd::getState Oct 16 18:57:42 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 16 18:57:42 tawshif volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 16 18:57:42 tawshif volumio[1048]: verbose: ControllerMpd::parseState Oct 16 18:57:42 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 18:57:42 tawshif volumio[1048]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 18:57:42 tawshif volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 16 18:57:42 tawshif volumio[1048]: info: ControllerMpd::pushState Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::servicePushState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151000,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2352 Kbps","isStreaming":false,"title":"It Takes A Woman","artist":"Chris Stapleton","album":"Higher","uri":"NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac","trackType":"flac"} Oct 16 18:57:42 tawshif volumio[1048]: verbose: CURRENT POSITION 3 Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 16 18:57:42 tawshif volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:42 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:42 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:42 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.796+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=151000 volume=100 Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.796+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_PLAYING positionMs=151000 volume=100 Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.796+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:42 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:42.797+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:42 tawshif volumio[1048]: info: ------------------------------ 22ms Oct 16 18:57:47 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 18:57:47 tawshif volumio[1048]: info: CURURI: music-library/NAS/DSD-2/Enrique Iglesias Oct 16 18:57:47 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:57:48 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::ClearQueue Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::stop Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::pushState Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 16 18:57:48 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Oct 16 18:57:48 tawshif volumio[1048]: info: MRS: Pushing multiroomSync output Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::serviceStop Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::getTrack 3 Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::serviceStop Oct 16 18:57:48 tawshif volumio[1048]: info: ControllerMpd::stop Oct 16 18:57:48 tawshif volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::clearPlayQueue Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::saveQueue Oct 16 18:57:48 tawshif volumio[1048]: info: CoreCommandRouter::volumioPushQueue Oct 16 18:57:48 tawshif volumio[1048]: info: CoreStateMachine::addQueueItems Oct 16 18:57:48 tawshif volumio[1048]: info: CorePlayQueue::addQueueItems Oct 16 18:57:48 tawshif volumio[1048]: info: Preload queue cleared Oct 16 18:57:48 tawshif volumio[1048]: info: Adding Item to queue: music-library/NAS/DSD-2/Enrique Iglesias/2007 - Insomniac Oct 16 18:57:48 tawshif volumio[1048]: info: Exploding uri music-library/NAS/DSD-2/Enrique Iglesias/2007 - Insomniac in service mpd Oct 16 18:57:48 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:48.807+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 16 18:57:48 tawshif volumio5-onboarding[1717]: time=2026-10-16T18:57:48.808+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.115:47704 @ 0x29525d0" id="mnt/NAS/DSD-2/Chris Stapleton/2023 - Higher [24Bit-96kHz]/04. It Takes A Woman.flac" title="4 - It Takes A Woman" Oct 16 18:57:48 tawshif volumio[1048]: info: Oct 16 18:57:48 tawshif volumio[1048]: ---------------------------- MPD announces state update: player Oct 16 18:57:48 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Enrique%20Iglesias/Insomniac/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FEnrique%20Iglesias%2F2007%20-%20Insomniac%2FEnrique%20Iglesias%20-%20Insomniac.cue&metadata=false Oct 16 18:57:48 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Enrique Iglesias/2007 - Insomniac/Enrique Iglesias - Insomniac.cue Oct 16 18:57:48 tawshif volumio[1048]: info: ALBUMART /albumart?cacheid=683&web=Enrique%20Iglesias/Insomniac/extralarge&path=%2Fmnt%2FNAS%2FDSD-2%2FEnrique%20Iglesias%2F2007%20-%20Insomniac%2FEnrique%20Iglesias%20-%20Insomniac.cue&metadata=false Oct 16 18:57:48 tawshif volumio[1048]: info: URI /mnt/NAS/DSD-2/Enrique Iglesias/2007 - Insomniac/Enrique Iglesias - Insomniac.cue Oct 16 18:57:48 tawshif volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:57:48 tawshif volumio[1048]: Error: Unable to resolve or reject the same promise twice Oct 16 18:57:48 tawshif volumio[1048]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 16 18:57:48 tawshif volumio[1048]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 16 18:57:48 tawshif volumio[1048]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 16 18:57:48 tawshif volumio[1048]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 16 18:57:48 tawshif volumio[1048]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 16 18:57:48 tawshif volumio[1048]: at Socket.emit (node:events:514:28) Oct 16 18:57:48 tawshif volumio[1048]: at addChunk (node:internal/streams/readable:343:12) Oct 16 18:57:48 tawshif volumio[1048]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 16 18:57:48 tawshif volumio[1048]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 18:57:48 tawshif volumio[1048]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 18:57:48 tawshif volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:57:50 tawshif sudo[4992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 18:56' Oct 16 18:57:50 tawshif sudo[4992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"