Aug 31 13:17:03 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:03.901+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:04 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:04 volumio volumio[1162]: info: CorePlayQueue::getTrack 5 Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:04 volumio volumio[1162]: verbose: New Socket.io Connection to 192.168.28.195:3000 from 192.168.28.148 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 13:17:04 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.369+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=468.003748ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.462+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=559.129865ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.462+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://www.googleapis.com duration=556.797628ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.462+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=556.925646ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.551+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=http://pushupdates.volumio.org duration=647.741951ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.571+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://securetoken.googleapis.com duration=666.356978ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.688+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://functions.volumio.cloud duration=783.371109ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.694+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://functions.volumio.cloud duration=788.297156ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.764+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=860.636377ms Aug 31 13:17:04 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:04.974+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://google.com duration=1.068835662s Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.032+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=https://database.volumio.cloud duration=1.128144637s Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.547+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=http://cddb.volumio.org duration=1.642286874s Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.582+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-112.498479ms timeout=10s endpoint=http://plugins.volumio.org duration=1.677070414s Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 5 Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 5 Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 5 Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:17:05 volumio volumio[1162]: info: [1788153425767] ControllerTidal::stop Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:05 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:17:05 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.770+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.770+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/7449419 title="Sarasate: Zigeunerweisen, Op. 20" Aug 31 13:17:05 volumio volumio[1162]: info: Aug 31 13:17:05 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:05 volumio volumio[1162]: info: sendMpdCommand stop took 18 milliseconds Aug 31 13:17:05 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:05 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:05 volumio volumio[1162]: info: [1788153425788] ControllerTidal::clearAddPlayTrack Aug 31 13:17:05 volumio volumio[1162]: info: Getting stream with soundQuality HI_RES Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:05 volumio volumio[1162]: info: sendMpdCommand status took 9 milliseconds Aug 31 13:17:05 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:05 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:05 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 31 13:17:05 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:05 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:05 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:05 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:05 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:05 volumio volumio[1162]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.834+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:05 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:05.835+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/77692444 title="Nocturne in C Sharp Minor" Aug 31 13:17:05 volumio volumio[1162]: info: ------------------------------ 51ms Aug 31 13:17:05 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:06 volumio volumio[1162]: info: getStreamUrl took 1015 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand stop took 1 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:17:06 volumio volumio[1162]: info: Aug 31 13:17:06 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:06 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand clear took 0 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMDdjYmU2NGZhZjhjOTY5ODQ4ZDBkNWU0MTllNzFhMC5tcDQ/0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==" Aug 31 13:17:06 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:17:06 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:06 volumio volumio[1162]: info: Aug 31 13:17:06 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:06 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMDdjYmU2NGZhZjhjOTY5ODQ4ZDBkNWU0MTllNzFhMC5tcDQ/0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==" took 1 milliseconds Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:17:06 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand play took 1 milliseconds Aug 31 13:17:06 volumio volumio[1162]: info: Aug 31 13:17:06 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:06 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:06 volumio volumio[1162]: info: Aug 31 13:17:06 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:06 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand status took 0 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:06 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:06 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:06 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMDdjYmU2NGZhZjhjOTY5ODQ4ZDBkNWU0MTllNzFhMC5tcDQ/0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==","trackType":"tidal"} Aug 31 13:17:06 volumio volumio[1162]: verbose: CURRENT POSITION 32 Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:17:06 volumio volumio[1162]: info: ------------------------------ 4ms Aug 31 13:17:06 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:17:06 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:06 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:06 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:06 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMDdjYmU2NGZhZjhjOTY5ODQ4ZDBkNWU0MTllNzFhMC5tcDQ/0.flac?token=1788157027~NDhjMjM0NDQ5YTQzYWU5MGYyM2U4NjQzZDJhNjBkNTE4ZmNhMmFlOQ==","trackType":"tidal"} Aug 31 13:17:06 volumio volumio[1162]: verbose: CURRENT POSITION 32 Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:17:06 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:06 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:06 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:06 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:06.941+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:06 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:06.942+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/77692444 title="Nocturne in C Sharp Minor" Aug 31 13:17:06 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:06.943+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:06 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:06.944+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/77692444 title="Nocturne in C Sharp Minor" Aug 31 13:17:06 volumio volumio[1162]: info: ------------------------------ 35ms Aug 31 13:17:07 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:08 volumio volumio[1162]: info: [1788153428681] ControllerTidal::next Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 32 Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:17:08 volumio volumio[1162]: info: [1788153428690] ControllerTidal::stop Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:08 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:17:08 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:08 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:08.692+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:08 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:08.692+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/77692444 title="Nocturne in C Sharp Minor" Aug 31 13:17:08 volumio volumio[1162]: info: Aug 31 13:17:08 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:08 volumio volumio[1162]: info: sendMpdCommand stop took 27 milliseconds Aug 31 13:17:08 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:08 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:08 volumio volumio[1162]: info: [1788153428718] ControllerTidal::clearAddPlayTrack Aug 31 13:17:08 volumio volumio[1162]: info: Getting stream with soundQuality HI_RES Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:08 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:08 volumio volumio[1162]: info: sendMpdCommand status took 11 milliseconds Aug 31 13:17:08 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:08 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:08 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:17:08 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:08 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:08 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:08 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:08 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:08 volumio volumio[1162]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 31 13:17:08 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:08.748+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:08 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:08.748+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/116097844 title="The Duel" Aug 31 13:17:08 volumio volumio[1162]: info: ------------------------------ 37ms Aug 31 13:17:09 volumio volumio[1162]: info: getStreamUrl took 683 milliseconds Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand stop took 0 milliseconds Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:17:09 volumio volumio[1162]: info: Aug 31 13:17:09 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:09 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand clear took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MDI3YmRlNWE4YjIzM2EzMWI5YzRhZmZkNWJjMWZiOS5tcDQ/0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==" Aug 31 13:17:09 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:17:09 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:09 volumio volumio[1162]: info: Aug 31 13:17:09 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:09 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MDI3YmRlNWE4YjIzM2EzMWI5YzRhZmZkNWJjMWZiOS5tcDQ/0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==" took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:17:09 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand play took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: info: Aug 31 13:17:09 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:09 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:09 volumio volumio[1162]: info: Aug 31 13:17:09 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:09 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:09 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:09 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:09 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:09 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MDI3YmRlNWE4YjIzM2EzMWI5YzRhZmZkNWJjMWZiOS5tcDQ/0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==","trackType":"tidal"} Aug 31 13:17:09 volumio volumio[1162]: verbose: CURRENT POSITION 8 Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:17:09 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:09 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:09 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MDI3YmRlNWE4YjIzM2EzMWI5YzRhZmZkNWJjMWZiOS5tcDQ/0.flac?token=1788157030~ZjdkNjE3MWQ0MWI0MGUxMGM1MTcwYWEyNTQyMzg5ZGY2MGI4N2ExOA==","trackType":"tidal"} Aug 31 13:17:09 volumio volumio[1162]: verbose: CURRENT POSITION 8 Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:17:09 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:09 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:09 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:09 volumio volumio[1162]: info: ------------------------------ 12ms Aug 31 13:17:09 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:09.994+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:09 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:09.995+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:09 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:09.996+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/116097844 title="The Duel" Aug 31 13:17:09 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:09.996+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/116097844 title="The Duel" Aug 31 13:17:10 volumio volumio[1162]: info: ------------------------------ 28ms Aug 31 13:17:10 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:19 volumio volumio[1162]: info: [1788153439113] ControllerTidal::next Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 8 Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:17:19 volumio volumio[1162]: info: [1788153439119] ControllerTidal::stop Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:19 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:19 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:19.121+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:19 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:19.123+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/116097844 title="The Duel" Aug 31 13:17:19 volumio volumio[1162]: info: Aug 31 13:17:19 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand stop took 42 milliseconds Aug 31 13:17:19 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:19 volumio volumio[1162]: info: [1788153439161] ControllerTidal::clearAddPlayTrack Aug 31 13:17:19 volumio volumio[1162]: info: Getting stream with soundQuality HI_RES Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand status took 11 milliseconds Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:19 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:19 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:19 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:19 volumio volumio[1162]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 31 13:17:19 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:19.205+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:19 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:19.206+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/106407645 title="Beethoven: Für Elise, Bagatelle in A Minor, WoO 59" Aug 31 13:17:19 volumio volumio[1162]: info: ------------------------------ 49ms Aug 31 13:17:19 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:19 volumio volumio[1162]: info: getStreamUrl took 826 milliseconds Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand stop took 1 milliseconds Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:17:19 volumio volumio[1162]: info: Aug 31 13:17:19 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:19 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand clear took 0 milliseconds Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NjRhZGI3MmY4ZDc2MTBhZDM4MjEzM2NjODIwMzM2Zi5tcDQ/0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==" Aug 31 13:17:19 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:17:19 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NjRhZGI3MmY4ZDc2MTBhZDM4MjEzM2NjODIwMzM2Zi5tcDQ/0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==" took 0 milliseconds Aug 31 13:17:19 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:19 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:17:19 volumio volumio[1162]: info: Aug 31 13:17:19 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:19 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:19 volumio volumio[1162]: info: sendMpdCommand play took 1 milliseconds Aug 31 13:17:19 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:20 volumio volumio[1162]: info: Aug 31 13:17:20 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:20 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:20 volumio volumio[1162]: info: Aug 31 13:17:20 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:20 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:20 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:20 volumio volumio[1162]: info: sendMpdCommand status took 2 milliseconds Aug 31 13:17:20 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:20 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:20 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:20 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NjRhZGI3MmY4ZDc2MTBhZDM4MjEzM2NjODIwMzM2Zi5tcDQ/0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==","trackType":"tidal"} Aug 31 13:17:20 volumio volumio[1162]: verbose: CURRENT POSITION 4 Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:17:20 volumio volumio[1162]: info: ------------------------------ 4ms Aug 31 13:17:20 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 13:17:20 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:20 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:20 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:20 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NjRhZGI3MmY4ZDc2MTBhZDM4MjEzM2NjODIwMzM2Zi5tcDQ/0.flac?token=1788157040~MWQ1ODk5NWI5OGIwNDI3ZDllMGU0ODg1YzIwZTU5OTAxYzc4NWY3ZQ==","trackType":"tidal"} Aug 31 13:17:20 volumio volumio[1162]: verbose: CURRENT POSITION 4 Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:17:20 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:20 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:20 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:20 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:20.140+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:20 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:20.141+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/106407645 title="Beethoven: Für Elise, Bagatelle in A Minor, WoO 59" Aug 31 13:17:20 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:20.143+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:20 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:20.143+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/106407645 title="Beethoven: Für Elise, Bagatelle in A Minor, WoO 59" Aug 31 13:17:20 volumio volumio[1162]: info: ------------------------------ 26ms Aug 31 13:17:20 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:22 volumio volumio[1162]: info: [1788153442062] ControllerTidal::next Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 4 Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:17:22 volumio volumio[1162]: info: [1788153442070] ControllerTidal::stop Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.072+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.073+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/106407645 title="Beethoven: Für Elise, Bagatelle in A Minor, WoO 59" Aug 31 13:17:22 volumio volumio[1162]: info: Aug 31 13:17:22 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand stop took 55 milliseconds Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: info: [1788153442129] ControllerTidal::clearAddPlayTrack Aug 31 13:17:22 volumio volumio[1162]: info: Getting stream with soundQuality HI_RES Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand status took 19 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:22 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.190+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.190+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/135641584 title="Beethoven: Triple Concerto in C Major, Op. 56: II. Largo - attacca" Aug 31 13:17:22 volumio volumio[1162]: info: ------------------------------ 82ms Aug 31 13:17:22 volumio volumio[1162]: info: getStreamUrl took 438 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand stop took 1 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:17:22 volumio volumio[1162]: info: Aug 31 13:17:22 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:22 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand clear took 1 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Yjk3ZDcyZjMwMWFmOTViYWYzNDBjYzk3MTNkOTM2Zi5tcDQ/0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==" Aug 31 13:17:22 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:17:22 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:22 volumio volumio[1162]: info: Aug 31 13:17:22 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:17:22 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Yjk3ZDcyZjMwMWFmOTViYWYzNDBjYzk3MTNkOTM2Zi5tcDQ/0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==" took 1 milliseconds Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:17:22 volumio volumio[1162]: info: ------------------------------ 1ms Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand play took 1 milliseconds Aug 31 13:17:22 volumio volumio[1162]: info: Aug 31 13:17:22 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:22 volumio volumio[1162]: info: Aug 31 13:17:22 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand status took 3 milliseconds Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Yjk3ZDcyZjMwMWFmOTViYWYzNDBjYzk3MTNkOTM2Zi5tcDQ/0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","trackType":"tidal"} Aug 31 13:17:22 volumio volumio[1162]: verbose: CURRENT POSITION 14 Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:17:22 volumio volumio[1162]: info: ------------------------------ 10ms Aug 31 13:17:22 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 31 13:17:22 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:17:22 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:17:22 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:17:22 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Yjk3ZDcyZjMwMWFmOTViYWYzNDBjYzk3MTNkOTM2Zi5tcDQ/0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","trackType":"tidal"} Aug 31 13:17:22 volumio volumio[1162]: verbose: CURRENT POSITION 14 Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:17:22 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:22 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:17:22 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.835+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.836+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/135641584 title="Beethoven: Triple Concerto in C Major, Op. 56: II. Largo - attacca" Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.840+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:17:22 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:22.841+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/135641584 title="Beethoven: Triple Concerto in C Major, Op. 56: II. Largo - attacca" Aug 31 13:17:22 volumio volumio[1162]: info: ------------------------------ 86ms Aug 31 13:17:22 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:48 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:48 volumio volumio[1162]: verbose: New Socket.io Connection to 192.168.28.195:3000 from 192.168.28.148 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 13:17:48 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.034+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-113.501116ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.069+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.165+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=96.376179ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.172+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=102.81958ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.176+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=105.98718ms Aug 31 13:17:50 volumio sudo[5917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 13:17:50 volumio sudo[5917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 13:17:50 volumio sudo[5917]: pam_unix(sudo:session): session closed for user root Aug 31 13:17:50 volumio sudo[5919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 13:17:50 volumio sudo[5919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.317+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://www.googleapis.com duration=247.515746ms Aug 31 13:17:50 volumio sudo[5919]: pam_unix(sudo:session): session closed for user root Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.321+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://google.com duration=251.92213ms Aug 31 13:17:50 volumio volumio[1162]: verbose: New Socket.io Connection to 192.168.28.195 from 192.168.28.148 UA: Mozilla/5.0 (Linux; Android 16; 25098PN5AC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.375+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=http://pushupdates.volumio.org duration=305.116811ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.459+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://securetoken.googleapis.com duration=389.708453ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.465+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=396.052615ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.465+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://functions.volumio.cloud duration=395.107328ms Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.465+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://functions.volumio.cloud duration=396.049985ms Aug 31 13:17:50 volumio sudo[5923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 13:17:50 volumio sudo[5923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 13:17:50 volumio sudo[5923]: pam_unix(sudo:session): session closed for user root Aug 31 13:17:50 volumio sudo[5925]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 13:17:50 volumio sudo[5925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 13:17:50 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:50.490+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=https://database.volumio.cloud duration=419.514078ms Aug 31 13:17:50 volumio sudo[5925]: pam_unix(sudo:session): session closed for user root Aug 31 13:17:50 volumio volumio[1162]: verbose: New Socket.io Connection to 192.168.28.195 from 192.168.28.148 UA: Mozilla/5.0 (Linux; Android 16; 25098PN5AC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::volumioGetQueue Aug 31 13:17:50 volumio volumio[1162]: info: CoreStateMachine::getQueue Aug 31 13:17:50 volumio volumio[1162]: info: CorePlayQueue::getQueue Aug 31 13:17:50 volumio volumio[1162]: info: Listing playlists Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 13:17:50 volumio volumio[1162]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 13:17:50 volumio volumio[1162]: info: Received Get System Info Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:50 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 13:17:50 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 13:17:51 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:51.357+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=http://cddb.volumio.org duration=1.287150981s Aug 31 13:17:51 volumio volumio5-onboarding[1464]: time=2026-08-31T13:17:51.359+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" latency=-111.030699ms timeout=10s endpoint=http://plugins.volumio.org duration=1.28877917s Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:17:52 volumio volumio[1162]: info: CURURI: music-library Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 13:17:52 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:17:52 volumio volumio[1162]: info: Received Get System Info Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:52 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 13:17:52 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:17:52 volumio volumio[1162]: info: CURURI: music-library/NAS Aug 31 13:17:52 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:17:53 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:17:53 volumio volumio[1162]: info: CURURI: music-library/NAS/music Aug 31 13:17:53 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:17:53 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/左右声道测试.wav Aug 31 13:17:53 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/左右声道测试.wav in service mpd Aug 31 13:17:53 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2F%E5%B7%A6%E5%8F%B3%E5%A3%B0%E9%81%93%E6%B5%8B%E8%AF%95.wav&metadata=false Aug 31 13:17:53 volumio volumio[1162]: info: URI /mnt/NAS/music/左右声道测试.wav Aug 31 13:17:54 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:17:54 volumio volumio[1162]: info: Received Get System Info Aug 31 13:17:54 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:54 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:54 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:54 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:54 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:17:56 volumio volumio[1162]: info: Received Get System Info Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:56 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 13:17:56 volumio volumio[1162]: info: Received Get System Info Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:17:56 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:17:56 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:17:58 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:17:58 volumio volumio[1162]: info: CURURI: music-library/NAS/music/mike Aug 31 13:17:58 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:00 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:18:00 volumio volumio[1162]: info: CURURI: music-library/NAS/music/mike/A欧美Hi-Res Aug 31 13:18:00 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 13:18:00 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:00 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/原声母带《老鹰乐队-加州旅馆》.wav Aug 31 13:18:00 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/原声母带《老鹰乐队-加州旅馆》.wav in service mpd Aug 31 13:18:00 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=The%20Eagles/Hotel%20California/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E5%8E%9F%E5%A3%B0%E6%AF%8D%E5%B8%A6%E3%80%8A%E8%80%81%E9%B9%B0%E4%B9%90%E9%98%9F-%E5%8A%A0%E5%B7%9E%E6%97%85%E9%A6%86%E3%80%8B.wav&metadata=false Aug 31 13:18:00 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/原声母带《老鹰乐队-加州旅馆》.wav Aug 31 13:18:01 volumio ntpd[1047]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 13:18:01 volumio ntpd[1047]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 13:18:01 volumio ntpd[1047]: DNS: Pool skipping: 198.18.0.228 Aug 31 13:18:01 volumio ntpd[1047]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 13:18:02 volumio ntpd[1047]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 13:18:02 volumio ntpd[1047]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 31 13:18:02 volumio ntpd[1047]: DNS: Pool skipping: 198.18.0.229 Aug 31 13:18:02 volumio ntpd[1047]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 31 13:18:03 volumio ntpd[1047]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 13:18:03 volumio ntpd[1047]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 31 13:18:03 volumio ntpd[1047]: DNS: Pool skipping: 198.18.0.230 Aug 31 13:18:03 volumio ntpd[1047]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 31 13:18:04 volumio ntpd[1047]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 13:18:04 volumio ntpd[1047]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 31 13:18:04 volumio ntpd[1047]: DNS: Pool skipping: 198.18.0.231 Aug 31 13:18:04 volumio ntpd[1047]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 13:18:09 volumio volumio[1162]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 13:18:09 volumio volumio[1162]: info: Received Get System Version Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 13:18:09 volumio volumio[1162]: info: Received Get System Info Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 13:18:09 volumio volumio[1162]: info: Discovery: Getting this device information Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:09 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 13:18:14 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::ClearQueue Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 14 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:18:14 volumio volumio[1162]: info: [1788153494292] ControllerTidal::stop Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::clearPlayQueue Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::saveQueue Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushQueue Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::addQueueItems Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::addQueueItems Aug 31 13:18:14 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:14 volumio volumio[1162]: info: Adding Item to queue: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声 Aug 31 13:18:14 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声 in service mpd Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.330+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.331+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id=tidal://song/135641584 title="Beethoven: Triple Concerto in C Major, Op. 56: II. Largo - attacca" Aug 31 13:18:14 volumio volumio[1162]: info: Aug 31 13:18:14 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand stop took 53 milliseconds Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Eagles/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F01.%20Hotel%20California.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Nana%20Mouskouri/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F02.%20Over%20And%20Over.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/02. Over And Over.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Crystal%20Gayle/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F03.%20Ready%20For%20The%20Times%20To%20Get%20Better.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Aaron%20Maville/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F04.%20Everybody%20Plays%20The%20Fool.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/04. Everybody Plays The Fool.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Matthew%20Lien/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F05.%20Bressanone.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/05. Bressanone.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Roberta%20Flack/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F06.%20Killing%20Me%20Softly%20With%20His%20Song.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/06. Killing Me Softly With His Song.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=%3F%D6%B2%EF%BF%BD%EF%BF%BD%3F%CF%BF/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F07.%20Your%20Latest%20Trick.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/07. Your Latest Trick.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Nana%20Mouskouri/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F08.%20Habanera%EF%BC%88Bizet%EF%BC%89.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/08. Habanera（Bizet）.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=%3F%3F%3F%3F%3F%D2%B2%3F%3F%3F%3F%3F/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F09.%20Besame%20Mucho%20%E7%83%AD%E6%83%85%E7%9B%B8%E6%8B%A5.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/09. Besame Mucho 热情相拥.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=All%204%20One/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F10.%20So%20Much%20In%20Love.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/10. So Much In Love.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Kenny%20G/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F11.%20Everytime%20I%20Close%20My%20Eyes.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/11. Everytime I Close My Eyes.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Glenn%20Frey/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F12.%20The%20One%20You%20Love.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/12. The One You Love.wav Aug 31 13:18:14 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Joe%20Cocker%20%26%20Jennifer%20Warnes/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F13.%20Up%20Where%20We%20Belong.wav&metadata=false Aug 31 13:18:14 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/13. Up Where We Belong.wav Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand status took 15 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushQueue Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::saveQueue Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPlay Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::play index 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand stop took 13 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: 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":"0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Yjk3ZDcyZjMwMWFmOTViYWYzNDBjYzk3MTNkOTM2Zi5tcDQ/0.flac?token=1788157043~NWRiMjg2YmIxODIzZTlkNGNkZjJhYWU0Mzg1N2U4ZGUwZjBkMjU0Zg==","trackType":"tidal"} Aug 31 13:18:14 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState stateService stop Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: No code Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.397+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.397+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.398+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.398+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:14 volumio volumio[1162]: info: ------------------------------ 61ms Aug 31 13:18:14 volumio volumio[1162]: info: Aug 31 13:18:14 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:14 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand clear took 49 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" Aug 31 13:18:14 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:14 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:18:14 volumio volumio[1162]: info: Aug 31 13:18:14 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:14 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:14 volumio volumio[1162]: info: ------------------------------ 11ms Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" took 10 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:18:14 volumio volumio[1162]: info: ------------------------------ 6ms Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand play took 5 milliseconds Aug 31 13:18:14 volumio volumio[1162]: info: Aug 31 13:18:14 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:14 volumio volumio[1162]: info: Aug 31 13:18:14 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand status took 2 milliseconds Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand status took 4 milliseconds Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":437,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:14 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:14 volumio volumio[1162]: info: ------------------------------ 8ms Aug 31 13:18:14 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 13:18:14 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:14 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":437,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:14 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:18:14 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:14 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:14 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.855+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.855+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.856+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:14 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:14.856+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:14 volumio volumio[1162]: info: ------------------------------ 31ms Aug 31 13:18:14 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.726+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.727+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio[1162]: info: Aug 31 13:18:17 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand stop took 13 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand status took 13 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand stop took 13 milliseconds Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:18:17 volumio volumio[1162]: info: Aug 31 13:18:17 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:17 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand clear took 5 milliseconds Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: 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":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:17 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState stateService stop Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: No code Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.770+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=11 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.770+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=11 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.772+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.772+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.770+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=11 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.775+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio[1162]: info: ------------------------------ 45ms Aug 31 13:18:17 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:18:17 volumio volumio[1162]: info: Aug 31 13:18:17 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:17 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:17 volumio volumio[1162]: info: ------------------------------ 28ms Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" took 26 milliseconds Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:18:17 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:17 volumio volumio[1162]: info: ------------------------------ 17ms Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand play took 15 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: Aug 31 13:18:17 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:17 volumio volumio[1162]: info: Aug 31 13:18:17 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand status took 2 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand status took 1 milliseconds Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 13:18:17 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:17 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":437,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:17 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:17 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":437,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:17 volumio volumio[1162]: verbose: CURRENT POSITION 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:18:17 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:17 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:17 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:17 volumio volumio[1162]: info: ------------------------------ 14ms Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.860+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=871 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.860+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.861+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=871 volume=100 Aug 31 13:18:17 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:17.862+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:17 volumio volumio[1162]: info: ------------------------------ 19ms Aug 31 13:18:17 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:21 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 13:18:21 volumio volumio[1162]: info: CURURI: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声 Aug 31 13:18:21 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/02. Over And Over.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/04. Everybody Plays The Fool.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/05. Bressanone.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/06. Killing Me Softly With His Song.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/07. Your Latest Trick.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/08. Habanera（Bizet）.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/09. Besame Mucho 热情相拥.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/10. So Much In Love.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/11. Everytime I Close My Eyes.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/12. The One You Love.wav Aug 31 13:18:21 volumio volumio[1162]: info: Preloading song: music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/13. Up Where We Belong.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Eagles/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F01.%20Hotel%20California.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/02. Over And Over.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Nana%20Mouskouri/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F02.%20Over%20And%20Over.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/02. Over And Over.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Crystal%20Gayle/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F03.%20Ready%20For%20The%20Times%20To%20Get%20Better.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/04. Everybody Plays The Fool.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Aaron%20Maville/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F04.%20Everybody%20Plays%20The%20Fool.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/04. Everybody Plays The Fool.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/05. Bressanone.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Matthew%20Lien/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F05.%20Bressanone.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/05. Bressanone.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/06. Killing Me Softly With His Song.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Roberta%20Flack/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F06.%20Killing%20Me%20Softly%20With%20His%20Song.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/06. Killing Me Softly With His Song.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/07. Your Latest Trick.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=%3F%D6%B2%EF%BF%BD%EF%BF%BD%3F%CF%BF/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F07.%20Your%20Latest%20Trick.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/07. Your Latest Trick.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/08. Habanera（Bizet）.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Nana%20Mouskouri/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F08.%20Habanera%EF%BC%88Bizet%EF%BC%89.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/08. Habanera（Bizet）.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/09. Besame Mucho 热情相拥.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=%3F%3F%3F%3F%3F%D2%B2%3F%3F%3F%3F%3F/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F09.%20Besame%20Mucho%20%E7%83%AD%E6%83%85%E7%9B%B8%E6%8B%A5.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/09. Besame Mucho 热情相拥.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/10. So Much In Love.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=All%204%20One/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F10.%20So%20Much%20In%20Love.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/10. So Much In Love.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/11. Everytime I Close My Eyes.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Kenny%20G/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F11.%20Everytime%20I%20Close%20My%20Eyes.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/11. Everytime I Close My Eyes.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/12. The One You Love.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Glenn%20Frey/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F12.%20The%20One%20You%20Love.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/12. The One You Love.wav Aug 31 13:18:21 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/欧美人声/13. Up Where We Belong.wav in service mpd Aug 31 13:18:21 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Joe%20Cocker%20%26%20Jennifer%20Warnes/%C4%A3%3F%3F%D6%AE%3F%3F%3F%3F%3F%3FCD%20%3F%3F%3F%3F%3F%3F%C6%B72%5B%C5%B7%3F%3F%3F%3F%3F%3F%5D/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E6%AC%A7%E7%BE%8E%E4%BA%BA%E5%A3%B0%2F13.%20Up%20Where%20We%20Belong.wav&metadata=false Aug 31 13:18:21 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/13. Up Where We Belong.wav Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioNext Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::next Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.480+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.480+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav" title="Hotel California" Aug 31 13:18:24 volumio volumio[1162]: info: Aug 31 13:18:24 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand stop took 20 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::play index undefined Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::startPlaybackTimer Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand status took 25 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand stop took 30 milliseconds Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 13:18:24 volumio volumio[1162]: info: Aug 31 13:18:24 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:24 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand clear took 2 milliseconds Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: 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":"Hotel California","artist":"Eagles","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/01. Hotel California.wav","trackType":"wav"} Aug 31 13:18:24 volumio volumio[1162]: verbose: CURRENT POSITION 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState stateService stop Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: No code Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=1 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=1 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=1 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.554+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:24 volumio volumio[1162]: info: ------------------------------ 61ms Aug 31 13:18:24 volumio volumio[1162]: error: updateQueue error: null Aug 31 13:18:24 volumio volumio[1162]: info: Aug 31 13:18:24 volumio volumio[1162]: ---------------------------- MPD announces system playlist update Aug 31 13:18:24 volumio volumio[1162]: info: Ignoring MPD Status Update Aug 31 13:18:24 volumio volumio[1162]: info: ------------------------------ 28ms Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand add "NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" took 27 milliseconds Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand play Aug 31 13:18:24 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:24 volumio volumio[1162]: info: ------------------------------ 12ms Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand play took 13 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: Aug 31 13:18:24 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:24 volumio volumio[1162]: info: Aug 31 13:18:24 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand status took 3 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::getState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand status Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand status took 4 milliseconds Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseState Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ready For The Times To Get Better","artist":"Crystal Gayle","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav","trackType":"wav"} Aug 31 13:18:24 volumio volumio[1162]: verbose: CURRENT POSITION 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus stop Aug 31 13:18:24 volumio volumio[1162]: info: ------------------------------ 9ms Aug 31 13:18:24 volumio volumio[1162]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 13:18:24 volumio volumio[1162]: verbose: ControllerMpd::parseTrackInfo Aug 31 13:18:24 volumio volumio[1162]: info: ControllerMpd::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::servicePushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Ready For The Times To Get Better","artist":"Crystal Gayle","album":"ģ??֮??????CD ??????Ʒ2[ŷ??????]","uri":"NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav","trackType":"wav"} Aug 31 13:18:24 volumio volumio[1162]: verbose: CURRENT POSITION 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState stateService play Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::syncState currentStatus play Aug 31 13:18:24 volumio volumio[1162]: info: Received an update from plugin. extracting info from payload Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:24 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:24 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.787+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.787+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.787+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:24 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:24.787+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:24 volumio volumio[1162]: info: ------------------------------ 35ms Aug 31 13:18:24 volumio volumio[1162]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 13:18:32 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::ClearQueue Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::stop Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::stPlaybackTimer Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::updateTrackBlock Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::getTrackBlock Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::pushState Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::volumioPushState Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::serviceStop Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::getTrack 2 Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::serviceStop Aug 31 13:18:32 volumio volumio[1162]: info: ControllerMpd::stop Aug 31 13:18:32 volumio volumio[1162]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::clearPlayQueue Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::saveQueue Aug 31 13:18:32 volumio volumio[1162]: info: CoreCommandRouter::volumioPushQueue Aug 31 13:18:32 volumio volumio[1162]: info: CoreStateMachine::addQueueItems Aug 31 13:18:32 volumio volumio[1162]: info: CorePlayQueue::addQueueItems Aug 31 13:18:32 volumio volumio[1162]: info: Preload queue cleared Aug 31 13:18:32 volumio volumio[1162]: info: Adding Item to queue: music-library/NAS/music/mike/A欧美Hi-Res/群星《欧美老歌 经典金曲101首》6CD WAV+CUE Aug 31 13:18:32 volumio volumio[1162]: info: Exploding uri music-library/NAS/music/mike/A欧美Hi-Res/群星《欧美老歌 经典金曲101首》6CD WAV+CUE in service mpd Aug 31 13:18:33 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:33.083+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 13:18:33 volumio volumio5-onboarding[1464]: time=2026-08-31T13:18:33.083+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:60714,00:00:00:00:00:00%01 @ 0x28c6420" id="mnt/NAS/music/mike/A欧美Hi-Res/欧美人声/03. Ready For The Times To Get Better.wav" title="Ready For The Times To Get Better" Aug 31 13:18:33 volumio volumio[1162]: info: Aug 31 13:18:33 volumio volumio[1162]: ---------------------------- MPD announces state update: player Aug 31 13:18:33 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=The%20Bells/Oldies%20101%20Your%20Favourite%20CD1/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E7%BE%A4%E6%98%9F%E3%80%8A%E6%AC%A7%E7%BE%8E%E8%80%81%E6%AD%8C%20%E7%BB%8F%E5%85%B8%E9%87%91%E6%9B%B2101%E9%A6%96%E3%80%8B6CD%20WAV%2BCUE%2FOldies%20101%20Your%20Favourite%20CD1%5BWAV%2BCUE%5D%2FVarious%20-%20Oldies%20101%20Your%20Favourite%20CD1.cue&metadata=false Aug 31 13:18:33 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/群星《欧美老歌 经典金曲101首》6CD WAV+CUE/Oldies 101 Your Favourite CD1[WAV+CUE]/Various - Oldies 101 Your Favourite CD1.cue Aug 31 13:18:33 volumio volumio[1162]: info: ALBUMART /albumart?cacheid=414&web=Santa%20Esmeralda/Oldies%20101%20Your%20Favourite%20CD1/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E6%AC%A7%E7%BE%8EHi-Res%2F%E7%BE%A4%E6%98%9F%E3%80%8A%E6%AC%A7%E7%BE%8E%E8%80%81%E6%AD%8C%20%E7%BB%8F%E5%85%B8%E9%87%91%E6%9B%B2101%E9%A6%96%E3%80%8B6CD%20WAV%2BCUE%2FOldies%20101%20Your%20Favourite%20CD1%5BWAV%2BCUE%5D%2FVarious%20-%20Oldies%20101%20Your%20Favourite%20CD1.cue&metadata=false Aug 31 13:18:33 volumio volumio[1162]: info: URI /mnt/NAS/music/mike/A欧美Hi-Res/群星《欧美老歌 经典金曲101首》6CD WAV+CUE/Oldies 101 Your Favourite CD1[WAV+CUE]/Various - Oldies 101 Your Favourite CD1.cue Aug 31 13:18:33 volumio volumio[1162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 13:18:33 volumio volumio[1162]: Error: Unable to resolve or reject the same promise twice Aug 31 13:18:33 volumio volumio[1162]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 31 13:18:33 volumio volumio[1162]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 31 13:18:33 volumio volumio[1162]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 31 13:18:33 volumio volumio[1162]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 31 13:18:33 volumio volumio[1162]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 31 13:18:33 volumio volumio[1162]: at Socket.emit (node:events:514:28) Aug 31 13:18:33 volumio volumio[1162]: at addChunk (node:internal/streams/readable:343:12) Aug 31 13:18:33 volumio volumio[1162]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 31 13:18:33 volumio volumio[1162]: at Readable.push (node:internal/streams/readable:253:10) Aug 31 13:18:33 volumio volumio[1162]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 31 13:18:33 volumio volumio[1162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 13:18:33 volumio sudo[6053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 13:17' Aug 31 13:18:33 volumio sudo[6053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"