Mar 26 10:18:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 26 10:18:04 volumio volumio[1218]: info: CURURI: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO Mar 26 10:18:04 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:04 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a Mar 26 10:18:04 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/02 트랙 2 Larghetto.m4a Mar 26 10:18:04 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/03 트랙 3 Rondo. Allegro.m4a Mar 26 10:18:04 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a in service mpd Mar 26 10:18:04 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/BEETHOVEN%3A%20VIOLIN%20CONCCERTO/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FBEETHOVEN-%20VIOLIN%20CONCERTO%2F01%20%ED%8A%B8%EB%9E%99%201%20Allegro%20ma%20non%20troppo.m4a&metadata=false Mar 26 10:18:04 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a Mar 26 10:18:04 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/02 트랙 2 Larghetto.m4a in service mpd Mar 26 10:18:04 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/BEETHOVEN%3A%20VIOLIN%20CONCCERTO/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FBEETHOVEN-%20VIOLIN%20CONCERTO%2F02%20%ED%8A%B8%EB%9E%99%202%20Larghetto.m4a&metadata=false Mar 26 10:18:04 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/02 트랙 2 Larghetto.m4a Mar 26 10:18:04 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/03 트랙 3 Rondo. Allegro.m4a in service mpd Mar 26 10:18:04 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/BEETHOVEN%3A%20VIOLIN%20CONCCERTO/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FBEETHOVEN-%20VIOLIN%20CONCERTO%2F03%20%ED%8A%B8%EB%9E%99%203%20Rondo.%20Allegro.m4a&metadata=false Mar 26 10:18:04 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/03 트랙 3 Rondo. Allegro.m4a Mar 26 10:18:05 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::ClearQueue Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::clearPlayQueue Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:05 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:05 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a Mar 26 10:18:05 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPlay Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::play index 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:05 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:05 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/02 트랙 2 Larghetto.m4a Mar 26 10:18:05 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/02 트랙 2 Larghetto.m4a Mar 26 10:18:05 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/03 트랙 3 Rondo. Allegro.m4a Mar 26 10:18:05 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/03 트랙 3 Rondo. Allegro.m4a Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::play index undefined Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::startPlaybackTimer Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::clearAddPlayTracks USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand stop took 3 milliseconds Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand clear took 2 milliseconds Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand add "USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: error: updateQueue error: null Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 6ms Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand add "USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" took 6 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 5ms Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 4ms Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand play Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces system playlist update Mar 26 10:18:05 volumio volumio[1218]: info: Ignoring MPD Status Update Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 7ms Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand play took 6 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 5ms Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 4ms Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 20 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 27 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 27 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 8 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 5 milliseconds Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 38ms Mar 26 10:18:05 volumio volumio[1218]: info: Mar 26 10:18:05 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand status took 7 milliseconds Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":779,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:18:05 volumio volumio[1218]: info: Received an update from plugin. extracting info from payload Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:18:05 volumio volumio[1218]: info: Received an update from plugin. extracting info from payload Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:18:05 volumio volumio[1218]: info: Received an update from plugin. extracting info from payload Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:18:05 volumio volumio[1218]: info: Received an update from plugin. extracting info from payload Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.744+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=779 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.744+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=779 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.747+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.747+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.749+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.748+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.749+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.749+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.750+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.751+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.752+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.751+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.753+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.753+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.753+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.754+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 76ms Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 76ms Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 62ms Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 55ms Mar 26 10:18:05 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 26 10:18:05 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:05 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:05 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState stateService play Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus play Mar 26 10:18:05 volumio volumio[1218]: info: Received an update from plugin. extracting info from payload Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.767+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.767+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=881 volume=45 Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.768+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:05.768+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:05 volumio volumio[1218]: info: ------------------------------ 55ms Mar 26 10:18:05 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:05 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:05 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Mar 26 10:18:08 volumio go-librespot[1608]: time="2026-03-26T10:18:08+09:00" level=trace msg="received accesspoint ping" Mar 26 10:18:08 volumio go-librespot[1608]: time="2026-03-26T10:18:08+09:00" level=trace msg="received accesspoint pong ack" Mar 26 10:18:08 volumio go-librespot[1608]: time="2026-03-26T10:18:08+09:00" level=trace msg="sent dealer ping" Mar 26 10:18:09 volumio go-librespot[1608]: time="2026-03-26T10:18:09+09:00" level=trace msg="received dealer pong" Mar 26 10:18:17 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:17.931+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=12.122259ms timeout=3s volume=46 Mar 26 10:18:17 volumio volumio[1218]: info: VolumeController::SetAlsaVolume46 Mar 26 10:18:17 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:17 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:17 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:17 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:17 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:17 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:17.946+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=12884 volume=46 Mar 26 10:18:17 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:17.947+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:17 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Mar 26 10:18:17 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 45 Mar 26 10:18:17 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 46 Mar 26 10:18:17 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:18 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:18.777+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=13.655027ms timeout=3s volume=47 Mar 26 10:18:18 volumio volumio[1218]: info: VolumeController::SetAlsaVolume47 Mar 26 10:18:18 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:18 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:18 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:18 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:18 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:18 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:18 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:18.790+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=13886 volume=47 Mar 26 10:18:18 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:18.790+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:18 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Mar 26 10:18:18 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 45 Mar 26 10:18:18 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 47 Mar 26 10:18:18 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:18 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 47 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.092+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=13.248671ms timeout=3s volume=48 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume48 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.105+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14136 volume=48 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.106+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 47 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 48 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.350+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.199217ms timeout=3s volume=49 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume49 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.364+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14387 volume=49 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.364+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 47 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 49 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 49 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.394+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=8.509616ms timeout=3s volume=50 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume50 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.410+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14387 volume=50 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.411+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 49 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 50 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.446+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=6.246224ms timeout=3s volume=51 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume51 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.459+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14387 volume=51 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.459+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 49 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 51 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 51 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.486+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.409866ms timeout=3s volume=52 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume52 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.500+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14637 volume=52 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.501+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 51 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 52 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.543+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=5.026261ms timeout=3s volume=53 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume53 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.556+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14637 volume=53 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.556+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 51 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 53 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 53 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.584+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=4.680149ms timeout=3s volume=54 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume54 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.599+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14637 volume=54 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.600+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 53 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 54 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.637+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=5.197897ms timeout=3s volume=55 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume55 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.651+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14637 volume=55 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.651+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 53 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 55 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 55 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.689+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.021764ms timeout=3s volume=56 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume56 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.702+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14637 volume=56 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.703+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 55 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 56 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.757+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=8.77836ms timeout=3s volume=57 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume57 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.770+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14888 volume=57 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.770+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 55 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 57 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 57 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.790+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=5.353888ms timeout=3s volume=58 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume58 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.803+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14888 volume=58 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.804+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 57 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 58 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.853+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=6.516722ms timeout=3s volume=59 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume59 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.865+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14888 volume=59 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.866+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 57 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 59 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 59 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.903+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=6.246515ms timeout=3s volume=60 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume60 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.915+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14888 volume=60 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 59 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 60 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.915+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.939+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=4.105364ms timeout=3s volume=61 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume61 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:19 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:19 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.952+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=14888 volume=61 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.952+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 59 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 61 Mar 26 10:18:19 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:19 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 61 Mar 26 10:18:19 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:19.991+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=5.032841ms timeout=3s volume=62 Mar 26 10:18:19 volumio volumio[1218]: info: VolumeController::SetAlsaVolume62 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.007+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=15138 volume=62 Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.007+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 61 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 62 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.048+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=6.112917ms timeout=3s volume=63 Mar 26 10:18:20 volumio volumio[1218]: info: VolumeController::SetAlsaVolume63 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=15138 volume=63 Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 61 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 63 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:20 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 63 Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.602+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.311891ms timeout=3s volume=64 Mar 26 10:18:20 volumio volumio[1218]: info: VolumeController::SetAlsaVolume64 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.615+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=15638 volume=64 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 63 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 64 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.618+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.943+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.833987ms timeout=3s volume=65 Mar 26 10:18:20 volumio volumio[1218]: info: VolumeController::SetAlsaVolume65 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:20 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:20 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.956+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=15888 volume=65 Mar 26 10:18:20 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:20.956+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 63 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 65 Mar 26 10:18:20 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:20 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 65 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.193+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=12.404149ms timeout=3s volume=66 Mar 26 10:18:21 volumio volumio[1218]: info: VolumeController::SetAlsaVolume66 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.206+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=16138 volume=66 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.207+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 65 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 66 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.409+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.643197ms timeout=3s volume=67 Mar 26 10:18:21 volumio volumio[1218]: info: VolumeController::SetAlsaVolume67 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.421+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=16388 volume=67 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.422+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 65 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 67 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:21 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 67 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.628+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=8.866792ms timeout=3s volume=68 Mar 26 10:18:21 volumio volumio[1218]: info: VolumeController::SetAlsaVolume68 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.642+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=16638 volume=68 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.642+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 67 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 68 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.861+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=8.473094ms timeout=3s volume=69 Mar 26 10:18:21 volumio volumio[1218]: info: VolumeController::SetAlsaVolume69 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:21 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.874+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=16888 volume=69 Mar 26 10:18:21 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:21.875+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 67 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 69 Mar 26 10:18:21 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:21 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 69 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.063+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.029873ms timeout=3s volume=70 Mar 26 10:18:22 volumio volumio[1218]: info: VolumeController::SetAlsaVolume70 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.075+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=17138 volume=70 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.075+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 69 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 70 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.284+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.276208ms timeout=3s volume=71 Mar 26 10:18:22 volumio volumio[1218]: info: VolumeController::SetAlsaVolume71 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.296+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=17388 volume=71 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.297+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 69 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 71 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:22 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 71 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.506+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=10.158334ms timeout=3s volume=72 Mar 26 10:18:22 volumio volumio[1218]: info: VolumeController::SetAlsaVolume72 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.521+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=17638 volume=72 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.522+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 71 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 72 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.722+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.694824ms timeout=3s volume=73 Mar 26 10:18:22 volumio volumio[1218]: info: VolumeController::SetAlsaVolume73 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.735+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=17638 volume=73 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 71 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 73 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:22 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 73 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.925+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=8.841866ms timeout=3s volume=74 Mar 26 10:18:22 volumio volumio[1218]: info: VolumeController::SetAlsaVolume74 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:22 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:22 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.939+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=17897 volume=74 Mar 26 10:18:22 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:22.940+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 73 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 74 Mar 26 10:18:22 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.142+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.0831ms timeout=3s volume=75 Mar 26 10:18:23 volumio volumio[1218]: info: VolumeController::SetAlsaVolume75 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.155+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=18147 volume=75 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.155+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 73 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 75 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:23 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 75 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.360+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=11.678924ms timeout=3s volume=76 Mar 26 10:18:23 volumio volumio[1218]: info: VolumeController::SetAlsaVolume76 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.373+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=18397 volume=76 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.374+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 75 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 76 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.575+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.137286ms timeout=3s volume=77 Mar 26 10:18:23 volumio volumio[1218]: info: VolumeController::SetAlsaVolume77 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.589+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=18647 volume=77 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.590+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 75 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 77 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:23 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 77 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.800+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=9.605786ms timeout=3s volume=78 Mar 26 10:18:23 volumio volumio[1218]: info: VolumeController::SetAlsaVolume78 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:23 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:23 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.813+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=18897 volume=78 Mar 26 10:18:23 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:23.814+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 77 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 78 Mar 26 10:18:23 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.127+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=7.575298ms timeout=3s volume=79 Mar 26 10:18:24 volumio volumio[1218]: info: VolumeController::SetAlsaVolume79 Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:24 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:24 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:24 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.139+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=19147 volume=79 Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.140+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 77 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 79 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 26 10:18:24 volumio volumio[1218]: info: Setting Spotify Volume from Volumio: 79 Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.366+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" latency=11.147954ms timeout=3s volume=80 Mar 26 10:18:24 volumio volumio[1218]: info: VolumeController::SetAlsaVolume80 Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Mar 26 10:18:24 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:24 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:24 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:24 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.382+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PLAYING positionMs=19397 volume=80 Mar 26 10:18:24 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:24.382+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:24 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:25 volumio volumio[1218]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Mar 26 10:18:25 volumio volumio[1218]: info: Sending Spotify command with payload to local API: /player/volume Mar 26 10:18:25 volumio go-librespot[1608]: time="2026-03-26T10:18:25+09:00" level=debug msg="update volume requested to 51772/65535" Mar 26 10:18:25 volumio go-librespot[1608]: time="2026-03-26T10:18:25+09:00" level=debug msg="renewing login5 access token" Mar 26 10:18:25 volumio go-librespot[1608]: time="2026-03-26T10:18:25+09:00" level=info msg="authenticated Login5" username="31************************ka" Mar 26 10:18:26 volumio go-librespot[1608]: time="2026-03-26T10:18:26+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 26 10:18:26 volumio go-librespot[1608]: time="2026-03-26T10:18:26+09:00" level=trace msg="emitting websocket event: volume" Mar 26 10:18:26 volumio volumio[1218]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Mar 26 10:18:26 volumio volumio[1218]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::volumioPause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::servicePause Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::servicePause Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::pause Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand pause Mar 26 10:18:27 volumio volumio[1218]: info: Mar 26 10:18:27 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand pause took 2 milliseconds Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:27 volumio volumio[1218]: info: Mar 26 10:18:27 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:27 volumio volumio[1218]: info: Mar 26 10:18:27 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand status took 5 milliseconds Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22478,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:27 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState stateService pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22478,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:27 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState stateService pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.339+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PAUSED positionMs=22398 volume=80 Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.340+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.340+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PAUSED positionMs=22398 volume=80 Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.341+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:27 volumio volumio[1218]: info: ------------------------------ 17ms Mar 26 10:18:27 volumio volumio[1218]: info: ------------------------------ 17ms Mar 26 10:18:27 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 26 10:18:27 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:27 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22478,"duration":1555,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:27 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState stateService pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus pause Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:27 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:27 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:27 volumio volumio[1218]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.348+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_PAUSED positionMs=22398 volume=80 Mar 26 10:18:27 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:27.348+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:27 volumio volumio[1218]: info: ------------------------------ 22ms Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:27 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:33 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 26 10:18:33 volumio volumio[1218]: info: CURURI: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720 Mar 26 10:18:33 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track1.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track10.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track2.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track3.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track4.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track5.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track6.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track7.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track8.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Preloading song: music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track9.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track1.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track1.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track1.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track10.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track10.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track10.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track2.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track2.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track2.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track3.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track3.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track3.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track4.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track4.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track4.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track5.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track5.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track5.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track6.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track6.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track6.mp3 Mar 26 10:18:33 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track7.mp3 in service mpd Mar 26 10:18:33 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track7.mp3&metadata=false Mar 26 10:18:33 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track7.mp3 Mar 26 10:18:34 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track8.mp3 in service mpd Mar 26 10:18:34 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track8.mp3&metadata=false Mar 26 10:18:34 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track8.mp3 Mar 26 10:18:34 volumio volumio[1218]: info: Exploding uri music-library/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track9.mp3 in service mpd Mar 26 10:18:34 volumio volumio[1218]: info: ALBUMART /albumart?cacheid=354&web=/GREATEST%20HITS%20of%201720/extralarge&path=%2Fmnt%2FUSB%2F187E-D536%2F%EB%AE%A4%EC%A7%81%EC%84%9C%EB%B2%84%2F00classic%2FGREATEST%20HIITS%20of%201720%2F1_Track9.mp3&metadata=false Mar 26 10:18:34 volumio volumio[1218]: info: URI /mnt/USB/187E-D536/뮤직서버/00classic/GREATEST HIITS of 1720/1_Track9.mp3 Mar 26 10:18:36 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 26 10:18:36 volumio volumio[1218]: info: CURURI: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever Mar 26 10:18:36 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:37 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 26 10:18:38 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::ClearQueue Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::stPlaybackTimer Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::serviceStop Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::serviceStop Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::stop Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::clearPlayQueue Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:38 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/01 트랙 1.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/01 트랙 1.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/02 트랙 2.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/02 트랙 2.mp3 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.195+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.196+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id="mnt/USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a" title="트랙 1 Allegro ma non troppo" Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPlay Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::play index 1 Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:38 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/03 트랙 3.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/03 트랙 3.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/04 트랙 4.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/04 트랙 4.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/05 트랙 5.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/05 트랙 5.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/06 트랙 6.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/06 트랙 6.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/07 트랙 7.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/07 트랙 7.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/08 트랙 8.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/08 트랙 8.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/09 트랙 9.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/09 트랙 9.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/10 트랙 10.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/10 트랙 10.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/11 트랙 11.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/11 트랙 11.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/12 트랙 12.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/12 트랙 12.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/13 트랙 13.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/13 트랙 13.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/14 트랙 14.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/14 트랙 14.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/15 트랙 15.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/15 트랙 15.mp3 Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::play index undefined Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 1 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: info: Mar 26 10:18:38 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand stop took 16 milliseconds Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:38 volumio volumio[1218]: info: Mar 26 10:18:38 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:38 volumio volumio[1218]: info: Mar 26 10:18:38 volumio volumio[1218]: ---------------------------- MPD announces state update: player Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:18:38 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:38 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:38 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: No code Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:38 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: No code Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:18:38 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio[1218]: info: No code Mar 26 10:18:38 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:18:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:38 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.234+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.234+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.235+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.235+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.235+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.236+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.236+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.236+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.237+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.238+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.238+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:18:38 volumio volumio5-onboarding[1543]: time=2026-03-26T10:18:38.239+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:18:38 volumio volumio[1218]: info: ------------------------------ 36ms Mar 26 10:18:38 volumio volumio[1218]: info: ------------------------------ 36ms Mar 26 10:18:38 volumio volumio[1218]: info: ------------------------------ 35ms Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:18:38 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:18:38 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 26 10:18:38 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 26 10:18:38 volumio go-librespot[1608]: time="2026-03-26T10:18:38+09:00" level=trace msg="sent dealer ping" Mar 26 10:18:39 volumio go-librespot[1608]: time="2026-03-26T10:18:39+09:00" level=trace msg="received dealer pong" Mar 26 10:18:45 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:45 volumio volumio[1218]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::ClearQueue Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::clearPlayQueue Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:45 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:45 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/01 트랙 1.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/01 트랙 1.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:45 volumio volumio[1218]: info: CoreCommandRouter::volumioPlay Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::play index 0 Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::addQueueItems Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::addQueueItems Mar 26 10:18:45 volumio volumio[1218]: info: Preload queue cleared Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/02 트랙 2.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/02 트랙 2.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/03 트랙 3.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/03 트랙 3.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/04 트랙 4.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/04 트랙 4.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/05 트랙 5.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/05 트랙 5.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/06 트랙 6.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/06 트랙 6.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/07 트랙 7.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/07 트랙 7.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/08 트랙 8.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/08 트랙 8.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/09 트랙 9.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/09 트랙 9.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/10 트랙 10.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/10 트랙 10.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/11 트랙 11.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/11 트랙 11.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/12 트랙 12.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/12 트랙 12.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/13 트랙 13.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/13 트랙 13.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/14 트랙 14.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/14 트랙 14.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Adding Item to queue: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/15 트랙 15.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: Using cached record of: music-library/USB/187E-D536/뮤직서버/00classic/Galante forever/15 트랙 15.mp3 Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::stop Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:45 volumio volumio[1218]: info: CoreCommandRouter::volumioPushQueue Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::saveQueue Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::play index undefined Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:45 volumio volumio[1218]: info: CoreStateMachine::updateTrackBlock Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::getTrackBlock Mar 26 10:18:45 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:18:48 volumio volumio[1218]: info: CoreCommandRouter::volumioPlay Mar 26 10:18:48 volumio volumio[1218]: info: CoreStateMachine::play index undefined Mar 26 10:18:48 volumio volumio[1218]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 10:18:48 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: info: Retrieving Cloud Streaming UI Mar 26 10:19:04 volumio volumio[1218]: info: Getting Tidal Cloud Configuration Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: info: Getting Qobuz Cloud Configuration Mar 26 10:19:04 volumio volumio[1218]: info: Asking plugin for UI Config Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: info: Getting Spotify Cloud Configuration Mar 26 10:19:04 volumio volumio[1218]: info: Asking plugin for UI Config Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: info: Saving Spotify Acccount Mar 26 10:19:04 volumio volumio[1218]: info: Got Tidal Cloud Configuration Mar 26 10:19:04 volumio volumio[1218]: info: Got it Mar 26 10:19:04 volumio volumio[1218]: info: Got it Mar 26 10:19:04 volumio volumio[1218]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 10:19:04 volumio volumio[1218]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 10:19:04 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 10:19:05 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand update Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: info: Mar 26 10:19:06 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand update took 3 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 1 milliseconds Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: info: Command Router : Notfying DB Updatetrue Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: info: Mar 26 10:19:06 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: info: Mar 26 10:19:06 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 4 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 3 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand status took 2 milliseconds Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: info: Command Router : Notfying DB Updatetrue Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: info: Command Router : Notfying DB Updatetrue Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:06 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: No code Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.123+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.124+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.124+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.124+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.125+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.125+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio[1218]: info: ------------------------------ 106ms Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 26 10:19:06 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:06 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:06 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: No code Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:06 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio[1218]: info: No code Mar 26 10:19:06 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:06 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:06 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.152+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.153+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.153+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.153+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.154+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.154+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:06.154+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:06 volumio volumio[1218]: info: ------------------------------ 78ms Mar 26 10:19:06 volumio volumio[1218]: info: ------------------------------ 77ms Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:06 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: info: MPD Database updated - AlbumList cache refreshed Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: database Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: MPD Database updated - AlbumList cache refreshed Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: database Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: MPD Database updated - AlbumList cache refreshed Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: database Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: Mar 26 10:19:08 volumio volumio[1218]: ---------------------------- MPD announces state update: update Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::getState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand status Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 212 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 211 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 212 milliseconds Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: info: Command Router : Notfying DB Updatefalse Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 330 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 329 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 330 milliseconds Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: info: Command Router : Notfying DB Updatefalse Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 408 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 408 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand status took 408 milliseconds Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: info: Command Router : Notfying DB Updatefalse Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseState Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 85 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 10:19:08 volumio volumio[1218]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: verbose: ControllerMpd::parseTrackInfo Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: ControllerMpd::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::servicePushState Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: 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":"트랙 1 Allegro ma non troppo","artist":null,"album":"BEETHOVEN: VIOLIN CONCCERTO","uri":"USB/187E-D536/뮤직서버/00classic/BEETHOVEN- VIOLIN CONCERTO/01 트랙 1 Allegro ma non troppo.m4a","trackType":"m4a"} Mar 26 10:19:08 volumio volumio[1218]: verbose: CURRENT POSITION 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState stateService stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::syncState currentStatus stop Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio[1218]: info: No code Mar 26 10:19:08 volumio volumio[1218]: info: CoreStateMachine::pushState Mar 26 10:19:08 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.473+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.474+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.475+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.475+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.475+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.476+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.476+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.476+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.477+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.477+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.477+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.478+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.479+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.480+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.480+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.480+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.481+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.481+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.481+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.481+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.482+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.482+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.483+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.481+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.483+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.483+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.483+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.484+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.484+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.484+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 10:19:08 volumio volumio5-onboarding[1543]: time=2026-03-26T10:19:08.486+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.33:53844,00:00:00:00:00:00%02 @ 0x2a08870" id= title= Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 494ms Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 494ms Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 493ms Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 494ms Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 492ms Mar 26 10:19:08 volumio volumio[1218]: info: ------------------------------ 492ms Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: SPOTIFY VOLUME 79 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: VOLUMIO VOLUME 80 Mar 26 10:19:08 volumio volumio[1218]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 26 10:19:08 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 10:19:09 volumio go-librespot[1608]: time="2026-03-26T10:19:09+09:00" level=trace msg="sent dealer ping" Mar 26 10:19:09 volumio go-librespot[1608]: time="2026-03-26T10:19:09+09:00" level=trace msg="received dealer pong" Mar 26 10:19:13 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 10:19:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 10:19:18 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 26 10:19:18 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 26 10:19:18 volumio volumio[1218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 10:19:18 volumio volumio[1218]: Error: getaddrinfo -3007 Mar 26 10:19:18 volumio volumio[1218]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Mar 26 10:19:18 volumio volumio[1218]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Mar 26 10:19:18 volumio volumio[1218]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Mar 26 10:19:18 volumio volumio[1218]: code: -3007, Mar 26 10:19:18 volumio volumio[1218]: errno: -3007, Mar 26 10:19:18 volumio volumio[1218]: syscall: 'getaddrinfo' Mar 26 10:19:18 volumio volumio[1218]: } Mar 26 10:19:18 volumio volumio[1218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 10:19:19 volumio sudo[8622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 10:18' Mar 26 10:19:19 volumio sudo[8622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"