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