Mar 29 09:41:00 volumio volumio[1409]: info: All search sources collected, pushing search results Mar 29 09:41:03 volumio volumio[1409]: Searching plugin music_service/spop Mar 29 09:41:03 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 09:41:04 volumio volumio[1409]: info: All search sources collected, pushing search results Mar 29 09:41:09 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 29 09:41:09 volumio volumio[1409]: info: In handleBrowseUri, curUri=spotify:artist:5fa13NJjmn2uQ3dxZDi2Ge Mar 29 09:41:11 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:7k9aYmnQ1nrp3RMTxNtUQV Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:4Exp9kLCzcqu6bIcdtqbXI Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:15YePdrwoWzDN68BanP7r4 Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:5CfvT8WjzThz80UtTowSRW Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:5dEzjwYpSq7LtHOGYU9iah Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:0XBecTa1OyEoZ3XKqXopY8 Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:5BMTHljcErdVq7FNB7WHOu Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:1ZrADntHAYV84R2y1Op3Eg Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:5cG5hU8eGu9hZ1RVsQgb8O Mar 29 09:41:11 volumio volumio[1409]: info: Preloading song: spotify:track:2wZdABuBWmMCjO7noA2LKp Mar 29 09:41:11 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:41:11 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:41:11 volumio volumio[1409]: info: No valid Plugin REST Endpoint Mar 29 09:41:11 volumio volumio[1409]: info: No valid Plugin REST Endpoint Mar 29 09:41:11 volumio go-librespot[2096]: time="2026-03-29T09:41:11+07:00" level=trace msg="sent dealer ping" Mar 29 09:41:11 volumio go-librespot[2096]: time="2026-03-29T09:41:11+07:00" level=trace msg="received dealer pong" Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::volumioPlay Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::play index undefined Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::startPlaybackTimer Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::resume Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand play Mar 29 09:41:19 volumio volumio[1409]: info: Mar 29 09:41:19 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand play took 8 milliseconds Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:19 volumio volumio[1409]: info: Mar 29 09:41:19 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:19 volumio volumio[1409]: info: Mar 29 09:41:19 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand status took 5 milliseconds Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand status took 4 milliseconds Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand status took 6 milliseconds Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480462,"duration":654,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 11","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav","trackType":"wav"} Mar 29 09:41:19 volumio volumio[1409]: verbose: CURRENT POSITION 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus pause Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480462,"duration":654,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 11","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav","trackType":"wav"} Mar 29 09:41:19 volumio volumio[1409]: verbose: CURRENT POSITION 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:19 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.977+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=479563 volume= Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.977+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.977+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=480462 volume= Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.978+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.981+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=480462 volume= Mar 29 09:41:19 volumio volumio[1409]: info: ------------------------------ 65ms Mar 29 09:41:19 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:19.982+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:19 volumio volumio[1409]: info: ------------------------------ 64ms Mar 29 09:41:19 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 29 09:41:19 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:19 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480462,"duration":654,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 11","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav","trackType":"wav"} Mar 29 09:41:19 volumio volumio[1409]: verbose: CURRENT POSITION 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:19 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:19 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:19 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:19 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:20 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:20.013+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=480462 volume= Mar 29 09:41:20 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:20.014+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:20 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:20.015+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=480462 volume= Mar 29 09:41:20 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:20.016+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:20 volumio volumio[1409]: info: ------------------------------ 97ms Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioNext Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::next Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::stop Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::stPlaybackTimer Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::updateTrackBlock Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrackBlock Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::serviceStop Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 10 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::serviceStop Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::stop Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.165+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_STOPPED positionMs=0 volume= Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.166+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav" title="11 - Track 11" Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand stop took 16 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::play index undefined Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::startPlaybackTimer Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::updateTrackBlock Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrackBlock Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 13 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand stop took 8 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 3 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 3 milliseconds Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand clear took 3 milliseconds Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: 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":"Track 11","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/11 Unknown Artist - Track 11.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService stop Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus stop Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: No code Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.227+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_STOPPED positionMs=0 volume= Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.228+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.228+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_STOPPED positionMs=0 volume= Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.230+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.230+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_STOPPED positionMs=0 volume= Mar 29 09:41:22 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:22.230+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 59ms Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: error: updateQueue error: null Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 48 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 49 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 48ms Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand add "USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" took 47 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 10ms Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 10ms Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand play Mar 29 09:41:22 volumio volumio[1409]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 09:41:22 volumio volumio[1409]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 64ms Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 62ms Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces system playlist update Mar 29 09:41:22 volumio volumio[1409]: info: Ignoring MPD Status Update Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 31ms Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand play took 35 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 36ms Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 35ms Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 6 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 13 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 13 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 8 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 4 milliseconds Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus stop Mar 29 09:41:22 volumio volumio[1409]: info: ------------------------------ 29ms Mar 29 09:41:22 volumio volumio[1409]: info: Mar 29 09:41:22 volumio volumio[1409]: ---------------------------- MPD announces state update: player Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::getState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 09:41:22 volumio volumio[1409]: info: sendMpdCommand status took 2 milliseconds Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::parseState Mar 29 09:41:22 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:22 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:22 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:22 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:22 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:22 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:22 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:22 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:22 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.001+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=456 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.001+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=456 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.002+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=456 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.002+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=456 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.004+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.004+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.004+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.005+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.005+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.005+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.006+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.007+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.008+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.005+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.008+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.008+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio[1409]: info: ------------------------------ 131ms Mar 29 09:41:23 volumio volumio[1409]: info: ------------------------------ 131ms Mar 29 09:41:23 volumio volumio[1409]: info: ------------------------------ 130ms Mar 29 09:41:23 volumio volumio[1409]: info: ------------------------------ 124ms Mar 29 09:41:23 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 104 milliseconds Mar 29 09:41:23 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Mar 29 09:41:23 volumio volumio[1409]: info: ControllerMpd::pushState Mar 29 09:41:23 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Mar 29 09:41:23 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:23 volumio volumio[1409]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":193,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 5","artist":null,"album":null,"uri":"USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav","trackType":"wav"} Mar 29 09:41:23 volumio volumio[1409]: verbose: CURRENT POSITION 4 Mar 29 09:41:23 volumio volumio[1409]: info: CoreStateMachine::syncState stateService play Mar 29 09:41:23 volumio volumio[1409]: info: CoreStateMachine::syncState currentStatus play Mar 29 09:41:23 volumio volumio[1409]: info: Received an update from plugin. extracting info from payload Mar 29 09:41:23 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:23 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:23 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:23 volumio volumio[1409]: info: CoreStateMachine::pushState Mar 29 09:41:23 volumio volumio[1409]: info: CorePlayQueue::getTrack 4 Mar 29 09:41:23 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.048+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.049+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.049+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" state=STATUS_PLAYING positionMs=844 volume= Mar 29 09:41:23 volumio volumio5-onboarding[2318]: time=2026-03-29T09:41:23.050+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.28:58772 @ 0xc00025a000" id="mnt/USB/Music/HF-Music/AV Show 2023 - Đĩa Tặng of Bá Hùng [CD-non-AccurateRip-LucPham]/05 Unknown Artist - Track 5.wav" title="5 - Track 5" Mar 29 09:41:23 volumio volumio[1409]: info: ------------------------------ 135ms Mar 29 09:41:41 volumio go-librespot[2096]: time="2026-03-29T09:41:41+07:00" level=trace msg="sent dealer ping" Mar 29 09:41:41 volumio go-librespot[2096]: time="2026-03-29T09:41:41+07:00" level=trace msg="received dealer pong" Mar 29 09:42:11 volumio go-librespot[2096]: time="2026-03-29T09:42:11+07:00" level=trace msg="sent dealer ping" Mar 29 09:42:11 volumio go-librespot[2096]: time="2026-03-29T09:42:11+07:00" level=trace msg="received dealer pong" Mar 29 09:42:15 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 29 09:42:15 volumio volumio[1409]: info: In handleBrowseUri, curUri=spotify:artist:165zSaCTY91rrT0OdoqhOj Mar 29 09:42:16 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:1oIbyY0XI0v8kybNIxESth Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:71MdCgnP7O0CfeQ4MxHs9b Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:2Lo8OS4uzBTPrcNRARvySg Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:4p2sMdiu5m2Mk1lhhwpQ6J Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:10LjgCh9EmVXa0oOPoFQIT Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:2Ewwxd7vHP6maSNZHrX7OD Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:4dLo6CbGD7kHNfeUM8zMw0 Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:6kViW74n0A6rB0WIgDU05t Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:6TQyGs0WID0qMIKSWncYOA Mar 29 09:42:16 volumio volumio[1409]: info: Preloading song: spotify:track:1UNMAa7HMTIZ6TpbO2gWQQ Mar 29 09:42:16 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:42:16 volumio volumio[1409]: info: Preload queue cleared Mar 29 09:42:17 volumio volumio[1409]: info: No valid Plugin REST Endpoint Mar 29 09:42:17 volumio volumio[1409]: info: No valid Plugin REST Endpoint Mar 29 09:42:21 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 29 09:42:21 volumio volumio[1409]: info: In handleBrowseUri, curUri=spotify:artist:4ydFKmkiBugqxm6O2EwhzO Mar 29 09:42:21 volumio volumio[1409]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 09:42:21 volumio volumio[1409]: TypeError: Cannot read properties of undefined (reading 'url') Mar 29 09:42:21 volumio volumio[1409]: at /data/plugins/music_service/spop/index.js:2446:60 Mar 29 09:42:21 volumio volumio[1409]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 29 09:42:21 volumio volumio[1409]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 09:42:22 volumio sudo[21624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 09:41' Mar 29 09:42:22 volumio sudo[21624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"