-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Wed 2025-04-16 14:36:00 +07. --
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::startPlaybackTimer
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::clearAddPlayTracks USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand stop took 11 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clear
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:00 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand status took 7 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand clear took 7 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3"
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:00 smsl-n100 volumio[973]: error: updateQueue error: null
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:00 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 27ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 21 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3" took 21 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand status took 15 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand play
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:00 smsl-n100 volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: No code
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 104ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 138ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:00 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 142ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand play took 133 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 18ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:00 smsl-n100 volumio[973]: info:
Apr 16 14:34:00 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand status took 65 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand status took 67 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand status took 64 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 45 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 41 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 37 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 16 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3","trackType":"mp3"}
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3","trackType":"mp3"}
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3","trackType":"mp3"}
Apr 16 14:34:00 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:00 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:00 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 226ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 226ms
Apr 16 14:34:00 smsl-n100 volumio[973]: info: ------------------------------ 215ms
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Preload queue cleared
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreStateMachine::ClearQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreStateMachine::stop
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CorePlayQueue::clearPlayQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CorePlayQueue::saveQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreStateMachine::addQueueItems
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CorePlayQueue::addQueueItems
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Preload queue cleared
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/01 Track 1.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/01 Track 1.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/02 Track 2.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CorePlayQueue::saveQueue
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CoreStateMachine::updateTrackBlock
Apr 16 14:34:01 smsl-n100 volumio[973]: info: CorePlayQueue::getTrackBlock
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPlay
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::play index 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::addQueueItems
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::addQueueItems
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Preload queue cleared
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/04 Track 4.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/04 Track 4.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/05 Track 5.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/05 Track 5.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/06 Track 6.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/06 Track 6.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/07 Track 7.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/07 Track 7.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/08 Track 8.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/08 Track 8.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/09 Track 9.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/09 Track 9.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/10 Track 10.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/10 Track 10.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/11 Track 11.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/11 Track 11.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/12 Track 12.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/12 Track 12.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/13 Track 13.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/13 Track 13.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/14 Track 14.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/14 Track 14.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/15 Track 15.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/15 Track 15.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushQueue
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::saveQueue
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::play index undefined
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::updateTrackBlock
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrackBlock
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::startPlaybackTimer
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::clearAddPlayTracks USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand stop took 21 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clear
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 12 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand clear took 12 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 8 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3"
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: CURRENT POSITION 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: No code
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 132ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:02 smsl-n100 volumio[973]: error: updateQueue error: null
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 140ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 134 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3" took 134 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 15ms
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand play
Apr 16 14:34:02 smsl-n100 volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 164ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:02 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 60ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand play took 53 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 49ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 25 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 24 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:02 smsl-n100 volumio[973]: info:
Apr 16 14:34:02 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 55 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 46 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 42 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 30 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand status took 23 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: CURRENT POSITION 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: CURRENT POSITION 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 184ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 176ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 134 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 122 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 118 milliseconds
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: CURRENT POSITION 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:02 smsl-n100 volumio[973]: verbose: CURRENT POSITION 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 2
Apr 16 14:34:02 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:02 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 280ms
Apr 16 14:34:02 smsl-n100 volumio[973]: info: ------------------------------ 242ms
Apr 16 14:34:03 smsl-n100 volumio[973]: error: Failed request for metavolumio API
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Preload queue cleared
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::ClearQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::clearPlayQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::saveQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::addQueueItems
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::addQueueItems
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Preload queue cleared
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Adding Item to queue: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Using cached record of: music-library/USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::saveQueue
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::updateTrackBlock
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrackBlock
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPlay
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::play index 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::play index undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::startPlaybackTimer
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::clearAddPlayTracks USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand stop took 10 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clear
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand status took 22 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand clear took 22 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3"
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:04 smsl-n100 volumio[973]: error: updateQueue error: null
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ------------------------------ 26ms
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 19 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand add "USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3" took 20 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand status took 12 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand play
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:04 smsl-n100 volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:04 smsl-n100 volumio[973]: info: No code
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ------------------------------ 124ms
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ------------------------------ 156ms
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces system playlist update
Apr 16 14:34:04 smsl-n100 volumio[973]: info: Ignoring MPD Status Update
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ------------------------------ 161ms
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand play took 151 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ------------------------------ 17ms
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:04 smsl-n100 volumio[973]: info:
Apr 16 14:34:04 smsl-n100 volumio[973]: ---------------------------- MPD announces state update: player
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::getState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand status
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand status took 43 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand status took 45 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand status took 41 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseState
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 39 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 35 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand clearerror took 33 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 17 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: ControllerMpd::parseTrackInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:04 smsl-n100 volumio[973]: info: ControllerMpd::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::servicePushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":473,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (4/1/2024 5:01:42 PM)","uri":"USB/PTC/Unknown album (4-1-2024 5-01-42 PM)/03 Track 3.mp3","trackType":"mp3"}
Apr 16 14:34:04 smsl-n100 volumio[973]: verbose: CURRENT POSITION 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState stateService pause
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::syncState currentStatus stop
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreStateMachine::pushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CorePlayQueue::getTrack 0
Apr 16 14:34:04 smsl-n100 volumio[973]: info: CoreCommandRouter::volumioPushState
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output update for this device
Apr 16 14:34:04 smsl-n100 volumio[973]: info: MRS: Pushing multiroomSync output
Apr 16 14:34:05 smsl-n100 volumio[973]: info: ------------------------------ 211ms
Apr 16 14:34:05 smsl-n100 volumio[973]: info: ------------------------------ 212ms
Apr 16 14:34:05 smsl-n100 volumio[973]: info: ------------------------------ 201ms
Apr 16 14:34:31 smsl-n100 ntpd[961]: Soliciting pool server 103.188.82.179
Apr 16 14:34:36 smsl-n100 ntpd[961]: Soliciting pool server 103.70.115.65
Apr 16 14:34:39 smsl-n100 ntpd[961]: Soliciting pool server 2400:6760:0:200::254
Apr 16 14:34:44 smsl-n100 ntpd[961]: Soliciting pool server 115.165.161.155
Apr 16 14:35:15 smsl-n100 systemd[1]: Starting Cleanup of Temporary Directories...
Apr 16 14:35:15 smsl-n100 volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 16 14:35:15 smsl-n100 systemd[1]: systemd-tmpfiles-clean.service: Succeeded.
Apr 16 14:35:15 smsl-n100 systemd[1]: Started Cleanup of Temporary Directories.
Apr 16 14:35:37 smsl-n100 ntpd[961]: Soliciting pool server 103.199.19.135
Apr 16 14:35:43 smsl-n100 ntpd[961]: Soliciting pool server 2401:5f80:5001:1f::31f
Apr 16 14:35:43 smsl-n100 ntpd[961]: Soliciting pool server 103.184.124.254
Apr 16 14:35:49 smsl-n100 ntpd[961]: Soliciting pool server 103.188.82.179
Apr 16 14:35:59 smsl-n100 volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 14:35:59 smsl-n100 volumio[973]: Error: connect ETIMEDOUT 151.101.78.79:443
Apr 16 14:35:59 smsl-n100 volumio[973]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Apr 16 14:35:59 smsl-n100 volumio[973]: errno: -110,
Apr 16 14:35:59 smsl-n100 volumio[973]: code: 'ETIMEDOUT',
Apr 16 14:35:59 smsl-n100 volumio[973]: syscall: 'connect',
Apr 16 14:35:59 smsl-n100 volumio[973]: address: '151.101.78.79',
Apr 16 14:35:59 smsl-n100 volumio[973]: port: 443
Apr 16 14:35:59 smsl-n100 volumio[973]: }
Apr 16 14:35:59 smsl-n100 volumio[973]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 14:36:00 smsl-n100 sudo[3687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 14:34
Apr 16 14:36:00 smsl-n100 sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="smsl"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET"
VOLUMIO_VERSION="3.789"
VOLUMIO_HARDWARE="nanopineo3"
VOLUMIO_DEVICENAME="Nanopi Neo3"
VOLUMIO_VENDOR_MODEL="SMSL S100"
VOLUMIO_VENDOR="SMSL"
VOLUMIO_MODEL="S100"
VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"