Mar 04 20:26:00 volumio go-librespot[1494]: time="2026-03-04T20:26:00+09:00" level=trace msg="sent dealer ping" Mar 04 20:26:01 volumio go-librespot[1494]: time="2026-03-04T20:26:01+09:00" level=trace msg="received dealer pong" Mar 04 20:26:13 volumio go-librespot[1494]: time="2026-03-04T20:26:13+09:00" level=trace msg="received accesspoint ping" Mar 04 20:26:13 volumio go-librespot[1494]: time="2026-03-04T20:26:13+09:00" level=trace msg="received accesspoint pong ack" Mar 04 20:26:23 volumio nmbd[1107]: [2026/03/04 20:26:23.292906, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 04 20:26:23 volumio nmbd[1107]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.38 for name WORKGROUP<1d>. Mar 04 20:26:23 volumio nmbd[1107]: This response was from IP 192.168.10.212, reporting an IP address of 192.168.10.212. Mar 04 20:26:30 volumio go-librespot[1494]: time="2026-03-04T20:26:30+09:00" level=trace msg="sent dealer ping" Mar 04 20:26:31 volumio go-librespot[1494]: time="2026-03-04T20:26:31+09:00" level=trace msg="received dealer pong" Mar 04 20:26:44 volumio volumio[1125]: verbose: New Socket.io Connection to 192.168.10.38 from 192.168.10.4 UA: Mozilla/5.0 (Linux; Android 15; SM-F926N Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::volumioGetState Mar 04 20:26:44 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::volumioGetQueue Mar 04 20:26:44 volumio volumio[1125]: info: CoreStateMachine::getQueue Mar 04 20:26:44 volumio volumio[1125]: info: CorePlayQueue::getQueue Mar 04 20:26:44 volumio volumio[1125]: info: Listing playlists Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 20:26:44 volumio volumio[1125]: info: Received Get System Info Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 20:26:44 volumio volumio[1125]: info: Discovery: Getting this device information Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::volumioGetState Mar 04 20:26:44 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::volumioGetState Mar 04 20:26:44 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:44 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 20:26:46 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 20:26:46 volumio volumio[1125]: info: Received Get System Info Mar 04 20:26:46 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 20:26:46 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 20:26:46 volumio volumio[1125]: info: Discovery: Getting this device information Mar 04 20:26:46 volumio volumio[1125]: info: CoreCommandRouter::volumioGetState Mar 04 20:26:46 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:46 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::ClearQueue Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::stop Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::stPlaybackTimer Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::updateTrackBlock Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrackBlock Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::serviceStop Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 7 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::serviceStop Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::stop Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::clearPlayQueue Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::saveQueue Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushQueue Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::addQueueItems Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::addQueueItems Mar 04 20:26:53 volumio volumio[1125]: info: Preload queue cleared Mar 04 20:26:53 volumio volumio[1125]: info: Adding Item to queue: music-library/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018) Mar 04 20:26:53 volumio volumio[1125]: info: Exploding uri music-library/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018) in service mpd Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.560+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.560+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Marie-Elisabeth Hecker - Elgar Cello Concerto, etc (24-48, Alpha, 2018)/08. Piano Quintet in A Minor, Op. 84 III. Andante-Allegro - Carolin Widmann.flac" title="8 - Piano Quintet in A Minor, Op. 84: III. Andante-Allegro - Carolin Widmann" Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Symphonieorchester%20des%20Bayerischen%20Rundfunks%20%26%20Mariss%20Jansons/Schubert%3A%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20%22Great%22/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMariss%20Jansons%20-%20Schubert%20-%20Symphony%20No8(9)%20(24-44%2C%20BR%2C%202018)%2F1.%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20'Great'%20_%20I.%20Andante%20-%20Allegro%20ma%20non%20troppo.flac&metadata=false Mar 04 20:26:53 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac Mar 04 20:26:53 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Symphonieorchester%20des%20Bayerischen%20Rundfunks%20%26%20Mariss%20Jansons/Schubert%3A%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20%22Great%22/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMariss%20Jansons%20-%20Schubert%20-%20Symphony%20No8(9)%20(24-44%2C%20BR%2C%202018)%2F2.%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20'Great'%20_%20II.%20Andante%20con%20moto.flac&metadata=false Mar 04 20:26:53 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/2. Symphony No. 9 in C Major, D. 944 'Great' _ II. Andante con moto.flac Mar 04 20:26:53 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Symphonieorchester%20des%20Bayerischen%20Rundfunks%20%26%20Mariss%20Jansons/Schubert%3A%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20%22Great%22/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMariss%20Jansons%20-%20Schubert%20-%20Symphony%20No8(9)%20(24-44%2C%20BR%2C%202018)%2F3.%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20'Great'%20_%20III.%20Scherzo_%20Allegro%20vivace.flac&metadata=false Mar 04 20:26:53 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/3. Symphony No. 9 in C Major, D. 944 'Great' _ III. Scherzo_ Allegro vivace.flac Mar 04 20:26:53 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Symphonieorchester%20des%20Bayerischen%20Rundfunks%20%26%20Mariss%20Jansons/Schubert%3A%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20%22Great%22/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMariss%20Jansons%20-%20Schubert%20-%20Symphony%20No8(9)%20(24-44%2C%20BR%2C%202018)%2F4.%20Symphony%20No.%209%20in%20C%20Major%2C%20D.%20944%20'Great'%20_%20IV.%20Allegro%20vivace.flac&metadata=false Mar 04 20:26:53 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/4. Symphony No. 9 in C Major, D. 944 'Great' _ IV. Allegro vivace.flac Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand stop took 32 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushQueue Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::saveQueue Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::updateTrackBlock Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrackBlock Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPlay Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::play index 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::stop Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::play index undefined Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::startPlaybackTimer Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 2 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand stop took 1 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 2 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 2 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand clear took 1 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Quintet in A Minor, Op. 84: III. Andante-Allegro - Carolin Widmann","artist":"Marie-Elisabeth Hecker, Antwerp Symphony Orchestra, Edo de Waart","album":"Elgar: Cello Concerto & Piano Quintet","uri":"NAS/FLAC/Classic/Marie-Elisabeth Hecker - Elgar Cello Concerto, etc (24-48, Alpha, 2018)/08. Piano Quintet in A Minor, Op. 84 III. Andante-Allegro - Carolin Widmann.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService stop Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus stop Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: No code Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.572+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.572+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.573+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.573+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.573+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.573+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 9ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 7ms Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: error: updateQueue error: null Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 4ms Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand add "NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" took 4 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand play Mar 04 20:26:53 volumio volumio[1125]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 7ms Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 3ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 3ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 3ms Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand play took 2 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:26:53 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 0ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 0ms Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 23 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 32 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 32 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 9 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 5 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus stop Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 38ms Mar 04 20:26:53 volumio volumio[1125]: info: Mar 04 20:26:53 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand status took 1 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:26:53 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:26:53 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:26:53 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:26:53 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.657+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.657+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.658+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.658+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.659+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.659+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.659+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.659+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.660+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.661+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.662+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.663+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.664+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.664+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 57ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 58ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 36ms Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 31ms Mar 04 20:26:53 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 04 20:26:53 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:26:53 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":930,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1020 Kbps","isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:26:53 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:26:53 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:26:53 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:26:53 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.673+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.674+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio[1125]: info: ------------------------------ 29ms Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.678+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=855 volume=100 Mar 04 20:26:53 volumio volumio5-onboarding[1442]: time=2026-03-04T20:26:53.678+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:26:53 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:00 volumio go-librespot[1494]: time="2026-03-04T20:27:00+09:00" level=trace msg="sent dealer ping" Mar 04 20:27:01 volumio go-librespot[1494]: time="2026-03-04T20:27:01+09:00" level=trace msg="received dealer pong" Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::ClearQueue Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::stop Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::stPlaybackTimer Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::updateTrackBlock Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrackBlock Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::serviceStop Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::serviceStop Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::stop Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::clearPlayQueue Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::saveQueue Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushQueue Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::addQueueItems Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::addQueueItems Mar 04 20:27:05 volumio volumio[1125]: info: Preload queue cleared Mar 04 20:27:05 volumio volumio[1125]: info: Adding Item to queue: music-library/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48] Mar 04 20:27:05 volumio volumio[1125]: info: Exploding uri music-library/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48] in service mpd Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.762+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.763+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac" title="1 - Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo" Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F01.%204.%20Adagietto%20(Arr.%20for%20Cello%20and%20Harp%20by%20Mischa%20Maisky).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F02.%202.%20Adagio%20(Arr.%20for%20Cello%20and%20Piano%20by%20Mischa%20Maisky).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/02. 2. Adagio (Arr. for Cello and Piano by Mischa Maisky).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F03.%202.%20Largo%20(Arr.%20for%20Cello%20and%20Piano%20by%20Sam%20Franco).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/03. 2. Largo (Arr. for Cello and Piano by Sam Franco).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F04.%20Act%202%20-%20'Ach%20ich%20fuhl's%2C%20es%20ist%20entschwunden'%20(Arr.%20for%20Cello%20and%20Piano%20by%20Mischa%20Maisky).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/04. Act 2 - 'Ach ich fuhl's, es ist entschwunden' (Arr. for Cello and Piano by Mischa Maisky).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F05.%20Act%202%20-%20'Mon%20coeur%20s'ouvre%20a%20ta%20voix'%20(Arr.%20for%20Cello%20and%20Piano%20by%20George%20Trinkaus).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/05. Act 2 - 'Mon coeur s'ouvre a ta voix' (Arr. for Cello and Piano by George Trinkaus).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F06.%20Act%202%20-%20Meditation%20(Arr.%20for%20Violin%20and%20Piano%20by%20Martin%20Marsick.flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/06. Act 2 - Meditation (Arr. for Violin and Piano by Martin Marsick.flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F07.%2010.%20October%20-%20Autumn%20Song%20(Arr.%20for%20Cello%20and%20Piano%20by%20Joachim%20Stutschewsky%20and%20Isco%20Thaler).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/07. 10. October - Autumn Song (Arr. for Cello and Piano by Joachim Stutschewsky and Isco Thaler).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F08.%206.%20Valse%20sentimentale%20(Arr.%20for%20Cello%20and%20Piano%20by%20Viktor%20Kubatsky).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/08. 6. Valse sentimentale (Arr. for Cello and Piano by Viktor Kubatsky).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F09.%2011.%20Etude%20in%20B%20Flat%20Minor%20(Arr.%20for%20Cello%20and%20Piano%20by%20Gregor%20Piatigorsky).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/09. 11. Etude in B Flat Minor (Arr. for Cello and Piano by Gregor Piatigorsky).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F10.%20Romance%20(Arr.%20for%20Cello%20and%20Piano%20by%20Steven%20Isserlis).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/10. Romance (Arr. for Cello and Piano by Steven Isserlis).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F11.%20Act%203%20-%20Solveig's%20Song%20(Arr.%20for%20Cello%20and%20Piano%20by%20Georg%20Goltermann).flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/11. Act 3 - Solveig's Song (Arr. for Cello and Piano by Georg Goltermann).flac Mar 04 20:27:05 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky%20%26%20Lily%20Maisky/Adagietto/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Adagietto%20-%202018%20%5B24-48%5D%2F12.%20Notturno%20in%20E%20Flat%20Major%2C%20Op.%20148%2C%20D.%20897.flac&metadata=false Mar 04 20:27:05 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/12. Notturno in E Flat Major, Op. 148, D. 897.flac Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand stop took 25 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushQueue Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::saveQueue Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::updateTrackBlock Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrackBlock Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPlay Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::play index 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::stop Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::play index undefined Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::startPlaybackTimer Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 2 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand stop took 1 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 2 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 1 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony No. 9 in C Major, D. 944 'Great' : I. Andante - Allegro ma non troppo","artist":"Symphonieorchester des Bayerischen Rundfunks & Mariss Jansons","album":"Schubert: Symphony No. 9 in C Major, D. 944 \"Great\"","uri":"NAS/FLAC/Classic/Mariss Jansons - Schubert - Symphony No8(9) (24-44, BR, 2018)/1. Symphony No. 9 in C Major, D. 944 'Great' _ I. Andante - Allegro ma non troppo.flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService stop Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus stop Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: No code Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.774+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.774+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.775+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.775+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.775+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.775+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 7ms Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand clear took 4 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 20:27:05 volumio volumio[1125]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 6ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 5ms Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: error: updateQueue error: null Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 4ms Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand add "NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" took 3 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 3ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 2ms Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand play Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces system playlist update Mar 04 20:27:05 volumio volumio[1125]: info: Ignoring MPD Status Update Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 2ms Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand play took 2 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 2ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 1ms Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 22 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 23 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 23 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 1 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus stop Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 31ms Mar 04 20:27:05 volumio volumio[1125]: info: Mar 04 20:27:05 volumio volumio[1125]: ---------------------------- MPD announces state update: player Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::getState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand status Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 8 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand status took 1 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseState Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"424 Kbps","isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:27:05 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:27:05 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1042 Kbps","isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:27:05 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=796 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.864+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.865+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=796 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.865+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.865+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.865+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 51ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 51ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 29ms Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 04 20:27:05 volumio volumio[1125]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: verbose: ControllerMpd::parseTrackInfo Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1042 Kbps","isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:27:05 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: ControllerMpd::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::servicePushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":619,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1042 Kbps","isStreaming":false,"title":"4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)","artist":"Mischa Maisky","album":"Adagietto","uri":"NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac","trackType":"flac"} Mar 04 20:27:05 volumio volumio[1125]: verbose: CURRENT POSITION 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState stateService play Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::syncState currentStatus play Mar 04 20:27:05 volumio volumio[1125]: info: Received an update from plugin. extracting info from payload Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:05 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:05 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.869+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_PLAYING positionMs=985 volume=100 Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 32ms Mar 04 20:27:05 volumio volumio[1125]: info: ------------------------------ 24ms Mar 04 20:27:05 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:05.870+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:05 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:12 volumio volumio[1125]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::ClearQueue Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::stop Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::stPlaybackTimer Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::updateTrackBlock Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::getTrackBlock Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::pushState Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:12 volumio volumio[1125]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 20:27:12 volumio volumio[1125]: info: CoreCommandRouter::volumioPushState Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::serviceStop Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::getTrack 0 Mar 04 20:27:12 volumio volumio[1125]: info: CoreCommandRouter::serviceStop Mar 04 20:27:12 volumio volumio[1125]: info: ControllerMpd::stop Mar 04 20:27:12 volumio volumio[1125]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::clearPlayQueue Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::saveQueue Mar 04 20:27:12 volumio volumio[1125]: info: CoreCommandRouter::volumioPushQueue Mar 04 20:27:12 volumio volumio[1125]: info: CoreStateMachine::addQueueItems Mar 04 20:27:12 volumio volumio[1125]: info: CorePlayQueue::addQueueItems Mar 04 20:27:12 volumio volumio[1125]: info: Preload queue cleared Mar 04 20:27:12 volumio volumio[1125]: info: Adding Item to queue: music-library/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs) Mar 04 20:27:12 volumio volumio[1125]: info: Exploding uri music-library/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs) in service mpd Mar 04 20:27:12 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:12.923+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 20:27:12 volumio volumio5-onboarding[1442]: time=2026-03-04T20:27:12.923+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.4:40746 @ 0xc00057e1e0" id="mnt/NAS/FLAC/Classic/Mischa Maisky - Adagietto - 2018 [24-48]/01. 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky).flac" title="1 - 4. Adagietto (Arr. for Cello and Harp by Mischa Maisky)" Mar 04 20:27:12 volumio volumio[1125]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Haydn%20Cello%20Concerto%20in%20D%20major/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F01.%20Joseph%20Haydn%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20D%20Major%2C%20Hob.%20VIIb2%20-%201.%20Allegro%20moderato.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/01. Joseph Haydn _ Concerto for Violoncello and Orchestra in D Major, Hob. VIIb2 - 1. Allegro moderato.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Haydn%20Cello%20Concerto%20in%20D%20major/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F02.%20Joseph%20Haydn%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20D%20Major%2C%20Hob.%20VIIb2%20-%202.%20Adagio.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/02. Joseph Haydn _ Concerto for Violoncello and Orchestra in D Major, Hob. VIIb2 - 2. Adagio.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Haydn%20Cello%20Concerto%20in%20D%20major/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F03.%20Joseph%20Haydn%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20D%20Major%2C%20Hob.%20VIIb2%20-%203.%20Allegro.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/03. Joseph Haydn _ Concerto for Violoncello and Orchestra in D Major, Hob. VIIb2 - 3. Allegro.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Dvorak%20%3A%20Favourite%20Cello%20Concerto%20in%20B%20minor/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F04.%20Antonin%20Dvorak%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20B%20Minor%2C%20Op.%20104%20-%201.%20Allegro.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/04. Antonin Dvorak _ Concerto for Violoncello and Orchestra in B Minor, Op. 104 - 1. Allegro.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Dvorak%20%3A%20Favourite%20Cello%20Concerto%20in%20B%20minor/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F05.%20Antonin%20Dvorak%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20B%20Minor%2C%20Op.%20104%20-%202.%20Adagio%2C%20ma%20non%20troppo.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/05. Antonin Dvorak _ Concerto for Violoncello and Orchestra in B Minor, Op. 104 - 2. Adagio, ma non troppo.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Dvorak%20%3A%20Favourite%20Cello%20Concerto%20in%20B%20minor/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F06.%20Antonin%20Dvorak%20_%20Concerto%20for%20Violoncello%20and%20Orchestra%20in%20B%20Minor%2C%20Op.%20104%20-%203.%20Finale%2C%20Allegro%20moderato.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/06. Antonin Dvorak _ Concerto for Violoncello and Orchestra in B Minor, Op. 104 - 3. Finale, Allegro moderato.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Dvorak%20%3A%20Favourite%20Cello%20Concerto%20in%20B%20minor/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2F07.%20Antonin%20Dvorak_%20Silent%20Woods%2C%20Op.%2068%2C%20No.5%20-%20Lento%20e%20molto%20cantabile.flac&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/07. Antonin Dvorak_ Silent Woods, Op. 68, No.5 - Lento e molto cantabile.flac Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Favourite%20Cello%20Concertos/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2FMischa%20Maisky%20-%20Favourite%20Cello%20Concertos%20(Disc%201)-new.cue&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/Mischa Maisky - Favourite Cello Concertos (Disc 1)-new.cue Mar 04 20:27:12 volumio volumio[1125]: info: ALBUMART /albumart?cacheid=271&web=Mischa%20Maisky/Favourite%20Cello%20Concertos/medium&path=%2Fmnt%2FNAS%2FFLAC%2FClassic%2FMischa%20Maisky%20-%20Cello%20Concertos%20(DG%2C%202005%2C%202CDs)%2FCD%201%2FMischa%20Maisky%20-%20Favourite%20Cello%20Concertos%20(Disc%201)-new.cue&metadata=false Mar 04 20:27:12 volumio volumio[1125]: info: URI /mnt/NAS/FLAC/Classic/Mischa Maisky - Cello Concertos (DG, 2005, 2CDs)/CD 1/Mischa Maisky - Favourite Cello Concertos (Disc 1)-new.cue Mar 04 20:27:12 volumio volumio[1125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 20:27:12 volumio volumio[1125]: Error: Unable to resolve or reject the same promise twice Mar 04 20:27:12 volumio volumio[1125]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 04 20:27:12 volumio volumio[1125]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 04 20:27:12 volumio volumio[1125]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 04 20:27:12 volumio volumio[1125]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 04 20:27:12 volumio volumio[1125]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 04 20:27:12 volumio volumio[1125]: at Socket.emit (node:events:514:28) Mar 04 20:27:12 volumio volumio[1125]: at addChunk (node:internal/streams/readable:343:12) Mar 04 20:27:12 volumio volumio[1125]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 04 20:27:12 volumio volumio[1125]: at Readable.push (node:internal/streams/readable:253:10) Mar 04 20:27:12 volumio volumio[1125]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 04 20:27:12 volumio volumio[1125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 20:27:13 volumio sudo[802181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 20:26' Mar 04 20:27:13 volumio sudo[802181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Feb 17 15:22:36 UTC 2026" VOLUMIO_VERSION="4.100" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c2e25634a09fcfb4037e0a2cd6f77385"