Feb 08 22:02:23 volumio nmbd[1131]: [2026/02/08 22:02:23.718119, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 08 22:02:23 volumio nmbd[1131]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.38 for name WORKGROUP<1d>. Feb 08 22:02:23 volumio nmbd[1131]: This response was from IP 192.168.10.212, reporting an IP address of 192.168.10.212. Feb 08 22:02:28 volumio go-librespot[1511]: time="2026-02-08T22:02:28+09:00" level=trace msg="sent dealer ping" Feb 08 22:02:28 volumio go-librespot[1511]: time="2026-02-08T22:02:28+09:00" level=trace msg="received dealer pong" Feb 08 22:02:28 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 08 22:02:28 volumio volumio[1144]: info: CURURI: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012] Feb 08 22:02:28 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/01. Blue Rondo A La Turk.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/02. Strange Meadow Lark.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/04. Three To Get Ready.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/05. Kathy's Waltz.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/06. Everybody's Jumpin'.flac Feb 08 22:02:28 volumio volumio[1144]: info: Preloading song: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/07. Pick Up Sticks.flac Feb 08 22:02:28 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/01. Blue Rondo A La Turk.flac in service mpd Feb 08 22:02:28 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F01.%20Blue%20Rondo%20A%20La%20Turk.flac&metadata=false Feb 08 22:02:28 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/01. Blue Rondo A La Turk.flac Feb 08 22:02:28 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/02. Strange Meadow Lark.flac in service mpd Feb 08 22:02:28 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac in service mpd Feb 08 22:02:28 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F02.%20Strange%20Meadow%20Lark.flac&metadata=false Feb 08 22:02:28 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/02. Strange Meadow Lark.flac Feb 08 22:02:28 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F03.%20Take%20Five.flac&metadata=false Feb 08 22:02:28 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac Feb 08 22:02:28 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/04. Three To Get Ready.flac in service mpd Feb 08 22:02:28 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F04.%20Three%20To%20Get%20Ready.flac&metadata=false Feb 08 22:02:28 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/04. Three To Get Ready.flac Feb 08 22:02:29 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/05. Kathy's Waltz.flac in service mpd Feb 08 22:02:29 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F05.%20Kathy's%20Waltz.flac&metadata=false Feb 08 22:02:29 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/05. Kathy's Waltz.flac Feb 08 22:02:29 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/06. Everybody's Jumpin'.flac in service mpd Feb 08 22:02:29 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F06.%20Everybody's%20Jumpin'.flac&metadata=false Feb 08 22:02:29 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/06. Everybody's Jumpin'.flac Feb 08 22:02:29 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/07. Pick Up Sticks.flac in service mpd Feb 08 22:02:29 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Dave%20Brubeck%20Quartet/(1959)%20Time%20Out%20%5B2012%20AP%20SACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FDave%20Brubeck%20Quartet%20-%20(1959)%20Time%20Out%20%5B24-88%20AP%20SACD%202012%5D%2F07.%20Pick%20Up%20Sticks.flac&metadata=false Feb 08 22:02:29 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/07. Pick Up Sticks.flac Feb 08 22:02:29 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::serviceStop Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::stop Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Feb 08 22:02:29 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/01. Blue Rondo A La Turk.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/01. Blue Rondo A La Turk.flac Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/02. Strange Meadow Lark.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/02. Strange Meadow Lark.flac Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.961+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.961+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Chuck Mangione - Feels So Good (1977) [EAC-APE]/01. Feels So Good.flac" title="1 - Feels So Good" Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::play index 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Feb 08 22:02:29 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/04. Three To Get Ready.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/04. Three To Get Ready.flac Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/05. Kathy's Waltz.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/05. Kathy's Waltz.flac Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/06. Everybody's Jumpin'.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/06. Everybody's Jumpin'.flac Feb 08 22:02:29 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/07. Pick Up Sticks.flac Feb 08 22:02:29 volumio volumio[1144]: info: Using cached record of: music-library/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/07. Pick Up Sticks.flac Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::play index undefined Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand stop took 7 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand stop took 2 milliseconds Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand clear took 1 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 22:02:29 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: No code Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 22:02:29 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio[1144]: info: No code Feb 08 22:02:29 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:29 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:29 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=80 Feb 08 22:02:29 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:29.974+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 7ms Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 7ms Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: error: updateQueue error: null Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 7ms Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand add "NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" took 7 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 1ms Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 1ms Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:29 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Feb 08 22:02:29 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 10ms Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: info: Feb 08 22:02:29 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:02:29 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 4ms Feb 08 22:02:29 volumio volumio[1144]: info: sendMpdCommand play took 3 milliseconds Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 3ms Feb 08 22:02:29 volumio volumio[1144]: info: ------------------------------ 3ms Feb 08 22:02:29 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 29 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 28 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 28 milliseconds Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: Feb 08 22:02:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:02:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:02:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 37ms Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.038+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.039+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.041+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.041+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 41ms Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 40ms Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.041+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.042+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 12 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 11 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand status took 11 milliseconds Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.043+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.043+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 08 22:02:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:02:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:02:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:02:30 volumio volumio[1144]: verbose: CURRENT POSITION 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:02:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 30ms Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 30ms Feb 08 22:02:30 volumio volumio[1144]: info: ------------------------------ 30ms Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.059+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.059+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=0 volume=80 Feb 08 22:02:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:30.061+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 08 22:02:34 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:34.407+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.5:45052 @ 0xc000300570" latency=64.498959ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 08 22:02:34 volumio sudo[26841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 22:02:34 volumio sudo[26841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 22:02:34 volumio sudo[26841]: pam_unix(sudo:session): session closed for user root Feb 08 22:02:34 volumio sudo[26843]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 22:02:34 volumio sudo[26843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 22:02:34 volumio sudo[26843]: pam_unix(sudo:session): session closed for user root Feb 08 22:02:34 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.10.38 from 192.168.10.5 UA: Mozilla/5.0 (Linux; Android 15; SM-F926N Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 08 22:02:35 volumio sudo[26847]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 22:02:35 volumio sudo[26847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 22:02:35 volumio sudo[26847]: pam_unix(sudo:session): session closed for user root Feb 08 22:02:35 volumio sudo[26849]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 22:02:35 volumio sudo[26849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 22:02:35 volumio sudo[26849]: pam_unix(sudo:session): session closed for user root Feb 08 22:02:35 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.10.38 from 192.168.10.5 UA: Mozilla/5.0 (Linux; Android 15; SM-F926N Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetVisibleSources Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetQueue Feb 08 22:02:35 volumio volumio[1144]: info: CoreStateMachine::getQueue Feb 08 22:02:35 volumio volumio[1144]: info: CorePlayQueue::getQueue Feb 08 22:02:35 volumio volumio[1144]: info: Listing playlists Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 08 22:02:35 volumio volumio[1144]: info: Received Get System Info Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 22:02:35 volumio volumio[1144]: info: Discovery: Getting this device information Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 08 22:02:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 08 22:02:36 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 08 22:02:37 volumio volumio[1144]: info: Received Get System Info Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 22:02:37 volumio volumio[1144]: info: Discovery: Getting this device information Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:37 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 08 22:02:37 volumio volumio[1144]: info: Received Get System Info Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 22:02:37 volumio volumio[1144]: info: Discovery: Getting this device information Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:37 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:37 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.079+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=64.014655ms timeout=3s volume=81 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume81 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.091+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12011 volume=81 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.091+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 80 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 81 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.331+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=64.420669ms timeout=3s volume=82 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume82 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.342+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12262 volume=82 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.342+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 80 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 82 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 82 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.361+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.409787ms timeout=3s volume=83 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume83 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.373+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12262 volume=83 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.373+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 82 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 83 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.413+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=54.117007ms timeout=3s volume=84 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume84 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.420+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12262 volume=84 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 84 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 82 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 84 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 84 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.422+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.465+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.528509ms timeout=3s volume=85 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume85 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.476+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12262 volume=85 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.477+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 84 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 85 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.511+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.15294ms timeout=3s volume=86 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume86 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.518+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12455 volume=86 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.518+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 84 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 86 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 86 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.562+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.256309ms timeout=3s volume=87 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume87 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.574+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12512 volume=87 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.574+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 86 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 87 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.612+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.040822ms timeout=3s volume=88 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume88 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.619+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12512 volume=88 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.619+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 86 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 88 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 88 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.663+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.7872ms timeout=3s volume=89 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume89 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.674+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12512 volume=89 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.674+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 88 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 89 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.716+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=54.09165ms timeout=3s volume=90 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume90 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.727+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12512 volume=90 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.727+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 88 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 90 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 90 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.763+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.49622ms timeout=3s volume=91 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume91 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 90 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 91 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.772+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12706 volume=91 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.772+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.821+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.161451ms timeout=3s volume=92 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume92 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.828+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12763 volume=92 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.828+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 90 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 92 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 92 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.867+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.976932ms timeout=3s volume=93 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume93 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.874+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12763 volume=93 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.875+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 92 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 93 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.923+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.446026ms timeout=3s volume=94 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume94 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 92 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 94 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:42 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 94 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.931+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12763 volume=94 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.931+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.971+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.636142ms timeout=3s volume=95 Feb 08 22:02:42 volumio volumio[1144]: info: VolumeController::SetAlsaVolume95 Feb 08 22:02:42 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:42 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:42 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.978+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12763 volume=95 Feb 08 22:02:42 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:42.979+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 94 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 95 Feb 08 22:02:42 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.020+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.584675ms timeout=3s volume=96 Feb 08 22:02:43 volumio volumio[1144]: info: VolumeController::SetAlsaVolume96 Feb 08 22:02:43 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:43 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.030+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=12957 volume=96 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.030+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 94 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 96 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:43 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 96 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.070+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.100366ms timeout=3s volume=97 Feb 08 22:02:43 volumio volumio[1144]: info: VolumeController::SetAlsaVolume97 Feb 08 22:02:43 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:43 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.077+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=13014 volume=97 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.078+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 96 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 97 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.122+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.012766ms timeout=3s volume=98 Feb 08 22:02:43 volumio volumio[1144]: info: VolumeController::SetAlsaVolume98 Feb 08 22:02:43 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:43 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.131+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=13014 volume=98 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.131+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 96 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 98 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:43 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 98 Feb 08 22:02:43 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.170+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.555969ms timeout=3s volume=99 Feb 08 22:02:43 volumio volumio[1144]: info: VolumeController::SetAlsaVolume99 Feb 08 22:02:43 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:43 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.184+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=13014 volume=99 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.184+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 98 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 99 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.220+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.715644ms timeout=3s volume=100 Feb 08 22:02:43 volumio volumio[1144]: info: VolumeController::SetAlsaVolume100 Feb 08 22:02:43 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:43 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:43 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.227+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=13014 volume=100 Feb 08 22:02:43 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:43.228+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 98 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 100 Feb 08 22:02:43 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:43 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 100 Feb 08 22:02:43 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 08 22:02:43 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 08 22:02:44 volumio volumio[1144]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 08 22:02:44 volumio volumio[1144]: info: Sending Spotify command with payload to local API: /player/volume Feb 08 22:02:44 volumio go-librespot[1511]: time="2026-02-08T22:02:44+09:00" level=debug msg="update volume requested to 65535/65535" Feb 08 22:02:44 volumio go-librespot[1511]: time="2026-02-08T22:02:44+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 08 22:02:44 volumio go-librespot[1511]: time="2026-02-08T22:02:44+09:00" level=trace msg="emitting websocket event: volume" Feb 08 22:02:44 volumio volumio[1144]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Feb 08 22:02:44 volumio volumio[1144]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Feb 08 22:02:45 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 08 22:02:53 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:53.888+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=71.69275ms timeout=3s volume=99 Feb 08 22:02:53 volumio volumio[1144]: info: VolumeController::SetAlsaVolume99 Feb 08 22:02:53 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:53 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:53 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:53 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:53 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Feb 08 22:02:53 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 100 Feb 08 22:02:53 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 99 Feb 08 22:02:53 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:53 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:53.900+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=23776 volume=99 Feb 08 22:02:53 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:53.900+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.132+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=64.361597ms timeout=3s volume=98 Feb 08 22:02:54 volumio volumio[1144]: info: VolumeController::SetAlsaVolume98 Feb 08 22:02:54 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.140+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=24025 volume=98 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.140+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 100 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 98 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:54 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 98 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.169+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.186787ms timeout=3s volume=97 Feb 08 22:02:54 volumio volumio[1144]: info: VolumeController::SetAlsaVolume97 Feb 08 22:02:54 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.178+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=24025 volume=97 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.178+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 98 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 97 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.221+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.012491ms timeout=3s volume=96 Feb 08 22:02:54 volumio volumio[1144]: info: VolumeController::SetAlsaVolume96 Feb 08 22:02:54 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.231+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=24025 volume=96 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.231+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 98 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 96 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:54 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 96 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.266+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=53.257798ms timeout=3s volume=95 Feb 08 22:02:54 volumio volumio[1144]: info: VolumeController::SetAlsaVolume95 Feb 08 22:02:54 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.276+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=24217 volume=95 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.276+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 96 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 95 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 08 22:02:54 volumio volumio[1144]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 08 22:02:54 volumio volumio[1144]: info: Received Get System Version Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 22:02:54 volumio volumio[1144]: info: Received Get System Info Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.317+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=55.15381ms timeout=3s volume=94 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 22:02:54 volumio volumio[1144]: info: Discovery: Getting this device information Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 22:02:54 volumio volumio[1144]: info: VolumeController::SetAlsaVolume94 Feb 08 22:02:54 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:54 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:54 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 96 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 94 Feb 08 22:02:54 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.326+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=24275 volume=94 Feb 08 22:02:54 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:54.326+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:54 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 94 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.095+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=55.011955ms timeout=3s volume=93 Feb 08 22:02:55 volumio volumio[1144]: info: VolumeController::SetAlsaVolume93 Feb 08 22:02:55 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:55 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.105+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=25027 volume=93 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.106+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 94 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 93 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.359+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=70.445821ms timeout=3s volume=92 Feb 08 22:02:55 volumio volumio[1144]: info: VolumeController::SetAlsaVolume92 Feb 08 22:02:55 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:55 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.371+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=25278 volume=92 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.371+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 94 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 92 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:55 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 92 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.389+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.190758ms timeout=3s volume=91 Feb 08 22:02:55 volumio volumio[1144]: info: VolumeController::SetAlsaVolume91 Feb 08 22:02:55 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:55 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.395+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=25278 volume=91 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.396+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 92 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 91 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.439+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45052 @ 0xc000300570" latency=52.373121ms timeout=3s volume=90 Feb 08 22:02:55 volumio volumio[1144]: info: VolumeController::SetAlsaVolume90 Feb 08 22:02:55 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:02:55 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:02:55 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.452+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=25278 volume=90 Feb 08 22:02:55 volumio volumio5-onboarding[1426]: time=2026-02-08T22:02:55.452+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: SPOTIFY VOLUME 92 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: VOLUMIO VOLUME 90 Feb 08 22:02:55 volumio volumio[1144]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 08 22:02:55 volumio volumio[1144]: info: Setting Spotify Volume from Volumio: 90 Feb 08 22:02:56 volumio volumio[1144]: SPOTIFY: SETTING SPOTIFY VOLUME 90 Feb 08 22:02:56 volumio volumio[1144]: info: Sending Spotify command with payload to local API: /player/volume Feb 08 22:02:56 volumio go-librespot[1511]: time="2026-02-08T22:02:56+09:00" level=debug msg="update volume requested to 58981/65535" Feb 08 22:02:57 volumio go-librespot[1511]: time="2026-02-08T22:02:57+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 08 22:02:57 volumio go-librespot[1511]: time="2026-02-08T22:02:57+09:00" level=trace msg="emitting websocket event: volume" Feb 08 22:02:57 volumio volumio[1144]: SPOTIFY: received: {"type":"volume","data":{"value":90,"max":100}} Feb 08 22:02:57 volumio volumio[1144]: SPOTIFY: RECEIVED SPOTIFY VOLUME 90 Feb 08 22:02:58 volumio go-librespot[1511]: time="2026-02-08T22:02:58+09:00" level=trace msg="received accesspoint ping" Feb 08 22:02:58 volumio go-librespot[1511]: time="2026-02-08T22:02:58+09:00" level=trace msg="received accesspoint pong ack" Feb 08 22:02:58 volumio go-librespot[1511]: time="2026-02-08T22:02:58+09:00" level=trace msg="sent dealer ping" Feb 08 22:02:58 volumio go-librespot[1511]: time="2026-02-08T22:02:58+09:00" level=trace msg="received dealer pong" Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 22:03:07 volumio volumio[1144]: info: Discovery: Getting this device information Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Feb 08 22:03:07 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 22:03:07 volumio volumio[1144]: verbose: New Socket.io Connection to 192.168.10.38:3000 from 192.168.10.5 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 08 22:03:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::serviceStop Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 2 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::stop Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Feb 08 22:03:26 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:03:26 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks Feb 08 22:03:26 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks in service mpd Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.678+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.678+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac" title="3 - Take Five" Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F1-Tenderly.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F10-Danny%20Boy.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/10-Danny Boy.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F11-Since%20I%20Fell%20For%20You.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/11-Since I Fell For You.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F12-Fingerlero.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/12-Fingerlero.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F2-I%20Want%20to%20Hold%20Your%20Hand.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/2-I Want to Hold Your Hand.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F3-My%20Cherie%20Amour.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/3-My Cherie Amour.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F4-Naima.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/4-Naima.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F5-Tequila.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/5-Tequila.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F6-Dont%20Know%20Why.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/6-Dont Know Why.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F7-The%20Lady%20in%20My%20Life.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/7-The Lady in My Life.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F8-My%20One%20and%20Only%20Love.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/8-My One and Only Love.flac Feb 08 22:03:26 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/Guitar%20Man/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20Guitar%20Man%20(Concord%202011)%20hdtracks%2F9-Paper%20Moon.flac&metadata=false Feb 08 22:03:26 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/9-Paper Moon.flac Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand stop took 12 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::play index 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::play index undefined Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 4 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand stop took 1 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 0 milliseconds Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand clear took 1 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: 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":"Take Five","artist":"Dave Brubeck Quartet","album":"(1959) Time Out [2012 AP SACD]","uri":"NAS/FLAC/Jazz/Dave Brubeck Quartet - (1959) Time Out [24-88 AP SACD 2012]/03. Take Five.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: No code Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 22:03:26 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.692+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 10ms Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 7ms Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 7ms Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: error: updateQueue error: null Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 6ms Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand add "NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" took 5 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 1ms Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand play took 2 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:26 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: info: Feb 08 22:03:26 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:26 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:26 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 18ms Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.814+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.814+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.815+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.815+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.815+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.816+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 22ms Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.817+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.818+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 22ms Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 08 22:03:26 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:26 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:26 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":122,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2616 Kbps","isStreaming":false,"title":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:26 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:26 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:26 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:26 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.833+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.833+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.834+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.834+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.836+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.836+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.836+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.836+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.837+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.837+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.837+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=990 volume=90 Feb 08 22:03:26 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:26.837+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 41ms Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 41ms Feb 08 22:03:26 volumio volumio[1144]: info: ------------------------------ 39ms Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:26 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:28 volumio go-librespot[1511]: time="2026-02-08T22:03:28+09:00" level=trace msg="sent dealer ping" Feb 08 22:03:28 volumio go-librespot[1511]: time="2026-02-08T22:03:28+09:00" level=trace msg="received dealer pong" Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::serviceStop Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::stop Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Feb 08 22:03:30 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:03:30 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC] Feb 08 22:03:30 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC] in service mpd Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.730+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.730+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac" title="1 - Tenderly" Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F01%20-%20Give%20me%20the%20night.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F02%20-%20Lady%20love%20me%20(one%20more%20time).flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/02 - Lady love me (one more time).flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F03%20-%20Never%20give%20up%20on%20a%20good%20thing.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/03 - Never give up on a good thing.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F04%20-%20Love%20x%20Love.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/04 - Love x Love.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F05%20-%20Love%20ballad.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/05 - Love ballad.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F06%20-%20Nature%20boy.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/06 - Nature boy.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F07%20-%20This%20masquerade.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/07 - This masquerade.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F08%20-%20On%20broadway%20(live).flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/08 - On broadway (live).flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F09%20-%20White%20rabbit.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/09 - White rabbit.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F10%20-%20Breezin'.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/10 - Breezin'.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F11%20-%20Welcome%20into%20my%20world.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/11 - Welcome into my world.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F12%20-%20What's%20on%20your%20mind.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/12 - What's on your mind.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F13%20-%20Mimosa%20-%20with%20Jimmy%20Smith.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/13 - Mimosa - with Jimmy Smith.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F14%20-%20Off%20Broadway.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/14 - Off Broadway.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F15%20-%20Gonna%20love%20you%20more.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/15 - Gonna love you more.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F16%20-%20Unchained%20Melody.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/16 - Unchained Melody.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%201)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%201%2F17%20-%20The%20Greatest%20Love%20of%20all.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/17 - The Greatest Love of all.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F01%20-%20Turn%20your%20love%20around.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/01 - Turn your love around.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F02%20-%20In%20your%20eyes.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/02 - In your eyes.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F03%20-%20Shiver.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/03 - Shiver.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F04%20-%20Inside%20love%20(so%20personal).flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/04 - Inside love (so personal).flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F05%20-%2020-20.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/05 - 20-20.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F06%20-%20Love%20all%20the%20hurt%20away%20-%20with%20Areatha%20Franklin.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/06 - Love all the hurt away - with Areatha Franklin.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F07%20-%20Feel%20like%20making%20love.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/07 - Feel like making love.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F08%20-%20Let's%20do%20it%20again.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/08 - Let's do it again.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F09%20-%20The%20one%20for%20me.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/09 - The one for me.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F10%20-%20Kisses%20in%20the%20moonlight.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/10 - Kisses in the moonlight.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F11%20-%20Love%20will%20come%20again.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/11 - Love will come again.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F12%20-%20Nothing's%20gonna%20change%20my%20love%20for%20you.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/12 - Nothing's gonna change my love for you.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F13%20-%20Being%20with%20you.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/13 - Being with you.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F14%20-%20Moody's%20Mood.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/14 - Moody's Mood.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F15%20-%20God%20bles%20this%20child%20-George%20Benson%20and%20Al%20Jarreau.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/15 - God bles this child -George Benson and Al Jarreau.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F16%20-%20I%20only%20have%20eyes%20for%20you%20-feat.%20The%20Count%20Basie%20Orchestra.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/16 - I only have eyes for you -feat. The Count Basie Orchestra.flac Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F17%20-%20Beyond%20the%20sea%20(la%20mer).flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/17 - Beyond the sea (la mer).flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F18%20-%20When%20I%20fall%20in%20love%20-feat%20Indina%20Menzel.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/18 - When I fall in love -feat Indina Menzel.flac Feb 08 22:03:30 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=George%20Benson/The%20Ultimate%20Collection%20(Disc%202)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGeorge%20Benson%20-%20The%20Ultimate%20Collection%2C%202CD%20(2015)%20%5BFLAC%5D%2FCD%202%2F19%20-%20When%20love%20comes%20calling.flac&metadata=false Feb 08 22:03:30 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 2/19 - When love comes calling.flac Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand stop took 77 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::play index 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::play index undefined Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand stop took 1 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 1 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 0 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand clear took 4 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: 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":"Tenderly","artist":"George Benson","album":"Guitar Man","uri":"NAS/FLAC/Jazz/George Benson - Guitar Man (Concord 2011) hdtracks/1-Tenderly.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: No code Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 22:03:30 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.760+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.760+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 21ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 10ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 9ms Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.762+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.762+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.762+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.762+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: error: updateQueue error: null Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 8ms Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand add "NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" took 7 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 2ms Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Feb 08 22:03:30 volumio volumio[1144]: info: Ignoring MPD Status Update Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 4ms Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand play took 5 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 5ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 5ms Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 29 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 30 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 30 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: Feb 08 22:03:30 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"851 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 39ms Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.830+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.831+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 43ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 43ms Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand status took 13 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseState Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.842+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.843+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 26ms Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 26ms Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 08 22:03:30 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Feb 08 22:03:30 volumio volumio[1144]: info: ControllerMpd::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Give me the night","artist":"George Benson","album":"The Ultimate Collection (Disc 1)","uri":"NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac","trackType":"flac"} Feb 08 22:03:30 volumio volumio[1144]: verbose: CURRENT POSITION 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Feb 08 22:03:30 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:30 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.857+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.857+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: info: ------------------------------ 39ms Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.859+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_PLAYING positionMs=844 volume=90 Feb 08 22:03:30 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:30.859+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:30 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:38 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::stop Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::pushState Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 22:03:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::serviceStop Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Feb 08 22:03:38 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Feb 08 22:03:38 volumio volumio[1144]: info: ControllerMpd::stop Feb 08 22:03:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::saveQueue Feb 08 22:03:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Feb 08 22:03:38 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Feb 08 22:03:38 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Feb 08 22:03:38 volumio volumio[1144]: info: Preload queue cleared Feb 08 22:03:38 volumio volumio[1144]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009) Feb 08 22:03:38 volumio volumio[1144]: info: Exploding uri music-library/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009) in service mpd Feb 08 22:03:38 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:38.435+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" state=STATUS_STOPPED positionMs=0 volume=90 Feb 08 22:03:38 volumio volumio5-onboarding[1426]: time=2026-02-08T22:03:38.435+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45052 @ 0xc000300570" id="mnt/NAS/FLAC/Jazz/George Benson - The Ultimate Collection, 2CD (2015) [FLAC]/CD 1/01 - Give me the night.flac" title="1 - Give me the night" Feb 08 22:03:38 volumio volumio[1144]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Feb 08 22:03:38 volumio volumio[1144]: info: Feb 08 22:03:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:38 volumio volumio[1144]: info: sendMpdCommand stop took 34 milliseconds Feb 08 22:03:38 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:38 volumio volumio[1144]: info: Feb 08 22:03:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:38 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:38 volumio volumio[1144]: info: Feb 08 22:03:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Feb 08 22:03:38 volumio volumio[1144]: info: ControllerMpd::getState Feb 08 22:03:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F01.%20Dear%20Old%20Stockholm.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/01. Dear Old Stockholm.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F02.%20Pieranunzi.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/02. Pieranunzi.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F03.%20Vuelvo%20Al%20Sur.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/03. Vuelvo Al Sur.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F04.%20Alone%20Together.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/04. Alone Together.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F05.%20Le%20Chant%20Des%20Partisans.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/05. Le Chant Des Partisans.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F06.%20Just%20One%20Of%20Those%20Things.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/06. Just One Of Those Things.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F07.%20Zoom.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/07. Zoom.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F08.%20Impressions.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/08. Impressions.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F09.%20Souvenirs%20Souvenirs.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/09. Souvenirs Souvenirs.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F10.%20Here's%20The%20Intro.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/10. Here's The Intro.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F11.%20Here's%20To%20You.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/11. Here's To You.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2F12.%20Convite%20Para%20A%20Vida.flac&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/12. Convite Para A Vida.flac Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2FGiovanni%20Mirabassi%20-%20Giovanni%20Mirabassi%20%20Out%20of%20track.cue&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/Giovanni Mirabassi - Giovanni Mirabassi Out of track.cue Feb 08 22:03:38 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=271&web=Giovanni%20Mirabassi/Out%20Of%20Track/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FGiovanni%20Mirabassi%20-%20Out%20of%20Track%20(2009)%2FGiovanni%20Mirabassi%20-%20Giovanni%20Mirabassi%20%20Out%20of%20track.cue&metadata=false Feb 08 22:03:38 volumio volumio[1144]: info: URI /mnt/NAS/FLAC/Jazz/Giovanni Mirabassi - Out of Track (2009)/Giovanni Mirabassi - Giovanni Mirabassi Out of track.cue Feb 08 22:03:38 volumio volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 22:03:38 volumio volumio[1144]: Error: Unable to resolve or reject the same promise twice Feb 08 22:03:38 volumio volumio[1144]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 08 22:03:38 volumio volumio[1144]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Feb 08 22:03:38 volumio volumio[1144]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 08 22:03:38 volumio volumio[1144]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 08 22:03:38 volumio volumio[1144]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 08 22:03:38 volumio volumio[1144]: at Socket.emit (node:events:514:28) Feb 08 22:03:38 volumio volumio[1144]: at addChunk (node:internal/streams/readable:343:12) Feb 08 22:03:38 volumio volumio[1144]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 08 22:03:38 volumio volumio[1144]: at Readable.push (node:internal/streams/readable:253:10) Feb 08 22:03:38 volumio volumio[1144]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 08 22:03:38 volumio volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 22:03:38 volumio sudo[27208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 22:02' Feb 08 22:03:38 volumio sudo[27208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"