Feb 22 20:32:03 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 5 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 5 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:03 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:03 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:03 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:03 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.860Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.861Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/The Rolling Stones/Mick Jagger/Mick Jagger - 2004_Alfie (With Dave Stewart)(OST)/03 - New York Hustle - Mick Jagger & Dave Stewart.mp3" title="3 - New York Hustle" Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::play index 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:03 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:03 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand stop took 32 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand stop took 8 milliseconds Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand status took 14 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand clear took 14 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand status took 12 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand status took 5 milliseconds Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:03 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: No code Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:03 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio[1066]: info: No code Feb 22 20:32:03 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:03 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:03 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.936Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.936Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.937Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.938Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.939Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.940Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.945Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.946Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.947Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.948Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=22 volume=86 Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.948Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:03.949Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 63ms Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 58ms Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:03 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 72ms Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" took 69 milliseconds Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 18ms Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 16ms Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:03 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:32:03 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:32:03 volumio volumio[1066]: info: ------------------------------ 95ms Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:03 volumio volumio[1066]: info: Feb 22 20:32:03 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:03 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 46ms Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand play took 34 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 28ms Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 30ms Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: Feb 22 20:32:04 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 44 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 44 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 38 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 35 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand status took 30 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:04 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:04 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:04 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 110ms Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.206Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.207Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.208Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.209Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.210Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.210Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.211Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.212Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.212Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.213Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.214Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 137ms Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 137ms Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.218Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 104ms Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 22 20:32:04 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:04 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":351,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Common People","artist":"Pulp","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3","trackType":"mp3"} Feb 22 20:32:04 volumio volumio[1066]: verbose: CURRENT POSITION 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:04 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:04 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:04 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.250Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.251Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.252Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.252Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.253Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.254Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.256Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:04.257Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 142ms Feb 22 20:32:04 volumio volumio[1066]: info: ------------------------------ 141ms Feb 22 20:32:08 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::getTrack 1 Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:32:08 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:32:08 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:08 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:08 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:08 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:08 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:08 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:08.983Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:08 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:08.983Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3" title="Common People" Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::play index 2 Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:08 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:08 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:08 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:08 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:08 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand stop took 29 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand stop took 5 milliseconds Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 6 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand clear took 6 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 6 milliseconds Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: No code Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: No code Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.048Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.054Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.054Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.055Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.056Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.057Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.057Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.058Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.058Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=1 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.058Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.059Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 55ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 54ms Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 59ms Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 54 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" took 54 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 6ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 5ms Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:32:09 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 68ms Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:09 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 26ms Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand play took 24 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 22ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 22ms Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 15 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: Feb 22 20:32:09 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 28 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 20 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand status took 15 milliseconds Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:09 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:09 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 64ms Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.200Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.201Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.201Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.203Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.204Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 78ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 74ms Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.206Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.207Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.210Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 68 milliseconds Feb 22 20:32:09 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:09 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:09 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Bella Senz'anima","artist":"Riccardo Cocciante","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3","trackType":"mp3"} Feb 22 20:32:09 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:09 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:09 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:09 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.278Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.278Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.279Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.279Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.281Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.282Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.282Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.283Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.283Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.283Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.283Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:09 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:09.284Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 146ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 138ms Feb 22 20:32:09 volumio volumio[1066]: info: ------------------------------ 138ms Feb 22 20:32:13 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:13 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:13 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:13 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.310Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.311Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3" title="Bella Senz'anima" Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::play index 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:13 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:13 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand stop took 30 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand stop took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand clear took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: No code Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: No code Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.381Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.382Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.383Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.384Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.385Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.385Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.386Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.388Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.388Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.389Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=11 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 58ms Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 58ms Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 62ms Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" took 55 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 5ms Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 5ms Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:32:13 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 71ms Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:13 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 19ms Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand play took 17 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 15ms Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 14ms Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: Feb 22 20:32:13 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 9 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 11 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:13 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:13 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:13 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 50ms Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.567Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.567Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.568Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.568Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.571Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.572Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.577Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.577Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.580Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 76ms Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 77ms Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 75ms Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.584Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.585Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 22 20:32:13 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.588Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:13 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:13 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:13 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:13 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:13 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.622Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.622Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.625Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.626Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=0 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.628Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 117ms Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.626Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.632Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:13.633Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:13 volumio volumio[1066]: info: ------------------------------ 116ms Feb 22 20:32:25 volumio volumio[1066]: info: Removing uri mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 from favourites Feb 22 20:32:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , removeFromFavourites Feb 22 20:32:25 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [removeFromFavourites] in plugin mpd Feb 22 20:32:25 volumio volumio[1066]: info: Saving local item favourites Feb 22 20:32:25 volumio volumio[1066]: info: Pushing Favourites {"service":"mpd","uri":"mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","favourite":false} Feb 22 20:32:31 volumio volumio[1066]: info: Adding mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 to favourites using generic method Feb 22 20:32:31 volumio volumio[1066]: info: Saving local item favourites Feb 22 20:32:31 volumio volumio[1066]: info: Pushing Favourites {"uri":"mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","favourite":true} Feb 22 20:32:37 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 20:32:38 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 20:32:38 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 20:32:47 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:47 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:47 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:47 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.392Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.392Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::play index 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:32:47 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:32:47 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand stop took 36 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand stop took 8 milliseconds Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 9 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand clear took 8 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 6 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:47 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: No code Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:32:47 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: No code Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.479Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.479Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.480Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.481Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.481Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.487Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.487Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.488Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.488Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.489Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 64ms Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 63ms Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.492Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.493Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 68ms Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 65 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" took 65 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 7ms Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 6ms Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:32:47 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 81ms Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:32:47 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 41ms Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand play took 37 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 36ms Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 35ms Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: Feb 22 20:32:47 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand status took 15 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:47 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:47 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:47 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 41ms Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.587Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.591Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.592Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 56ms Feb 22 20:32:47 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 49 milliseconds Feb 22 20:32:47 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:32:47 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:32:47 volumio volumio[1066]: verbose: CURRENT POSITION 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:32:47 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:32:47 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:32:47 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.633Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.634Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.639Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:32:47 volumio volumio5-onboarding[1961]: time=2026-02-22T20:32:47.640Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:32:47 volumio volumio[1066]: info: ------------------------------ 103ms Feb 22 20:32:53 volumio volumio[1066]: info: Removing uri mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac from favourites Feb 22 20:32:53 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , removeFromFavourites Feb 22 20:32:53 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [removeFromFavourites] in plugin mpd Feb 22 20:32:53 volumio volumio[1066]: info: Saving local item favourites Feb 22 20:32:53 volumio volumio[1066]: info: Pushing Favourites {"service":"mpd","uri":"mnt/INTERNAL/Santana/1999 - Supernatural/13 - The Calling (featuring Eric Clapton).flac","favourite":false} Feb 22 20:33:06 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:33:06 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:33:06 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::play index 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:33:06 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:33:06 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand stop took 30 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand stop took 8 milliseconds Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 5 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand clear took 6 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 3 milliseconds Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: No code Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: No code Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.205Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.209Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.210Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.210Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.211Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.212Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.212Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.213Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.215Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.216Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 51ms Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.216Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 50ms Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.222Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 60ms Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" took 59 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 12ms Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 14ms Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:33:06 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 73ms Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:06 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 24ms Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand play took 17 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 16ms Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 15ms Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 4 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: Feb 22 20:33:06 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 8 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 5 milliseconds Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 19ms Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:06 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:06 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:06 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:06 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.418Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.419Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.421Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.421Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.420Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.423Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.426Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.427Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.428Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.428Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.429Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 83ms Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.429Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 82ms Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 83ms Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 79ms Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.434Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.435Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 22 20:33:06 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:06 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":473,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Why Did You Do It (12'')","artist":"STRETCH","album":null,"uri":"INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3","trackType":"mp3"} Feb 22 20:33:06 volumio volumio[1066]: verbose: CURRENT POSITION 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:06 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.436Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:06 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:06 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.441Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.448Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.449Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.450Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:06 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:06.450Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:06 volumio volumio[1066]: info: ------------------------------ 97ms Feb 22 20:33:07 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::ClearQueue Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::stPlaybackTimer Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::serviceStop Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 2 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::serviceStop Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::stop Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::clearPlayQueue Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::addQueueItems Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::addQueueItems Feb 22 20:33:07 volumio volumio[1066]: info: Preload queue cleared Feb 22 20:33:07 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Pulp - Common People.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Riccardo Cocciante - Bella Senz'anima.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Adding Item to queue: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:33:07 volumio volumio[1066]: info: Using cached record of: mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.623Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.623Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1b_Singlovi/Strani/Stretch - Why did you do it (12'')_1.mp3" title="Why Did You Do It (12'')" Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushQueue Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::saveQueue Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::updateTrackBlock Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrackBlock Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::play index 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::play index undefined Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3 Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand stop took 21 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand stop took 5 milliseconds Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 5 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand clear took 4 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 3 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: No code Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: No code Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.673Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.674Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.675Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.676Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.677Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.678Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.678Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.679Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.679Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.680Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.682Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.682Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 41ms Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 41ms Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: error: updateQueue error: null Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 46ms Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 45 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand add "INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" took 45 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 7ms Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 6ms Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Feb 22 20:33:07 volumio volumio[1066]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 56ms Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Feb 22 20:33:07 volumio volumio[1066]: info: Ignoring MPD Status Update Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 32ms Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand play took 28 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 28ms Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 23ms Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 28 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 28 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 18 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 17 milliseconds Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: Feb 22 20:33:07 volumio volumio[1066]: ---------------------------- MPD announces state update: player Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::getState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 21 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand status took 20 milliseconds Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseState Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:07 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:07 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:07 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 96ms Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.832Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.833Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.833Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 120ms Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 118ms Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 109ms Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.846Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.847Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.848Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.848Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.849Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.853Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.855Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.856Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 22 20:33:07 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:07 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: ControllerMpd::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":900,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Voodoo Chile","artist":"The Jimi Hendrix Experience","album":"Top 100 Greatest Guitar Solos","uri":"INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3","trackType":"mp3"} Feb 22 20:33:07 volumio volumio[1066]: verbose: CURRENT POSITION 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Feb 22 20:33:07 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio[1066]: info: CoreStateMachine::pushState Feb 22 20:33:07 volumio volumio[1066]: info: CorePlayQueue::getTrack 3 Feb 22 20:33:07 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.884Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.884Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.886Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.887Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.888Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.888Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.889Z level=INFO msg="emitting player state changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" state=STATUS_PLAYING positionMs=866 volume=86 Feb 22 20:33:07 volumio volumio5-onboarding[1961]: time=2026-02-22T20:33:07.894Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.10:44176 @ 0xc0003ac9f0" id="mnt/INTERNAL/Music/1a_kompilacije/Top 100 Greatest Guitar Solos/11 - The Jimi Hendrix Experience - Voodoo Chile.mp3" title="11 - Voodoo Chile" Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 131ms Feb 22 20:33:07 volumio volumio[1066]: info: ------------------------------ 130ms Feb 22 20:33:24 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:24 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:24 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:25 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:25 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:25 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:25 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:26 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:31 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:31 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:31 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:32 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:32 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:32 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:33 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:33 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:34 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:35 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:36 volumio volumio[1066]: Searching all installed plugins Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: , search Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 22 20:33:36 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 22 20:33:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 22 20:33:36 volumio volumio[1066]: info: All search sources collected, pushing search results Feb 22 20:33:38 volumio volumio[1066]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Feb 22 20:33:39 volumio volumio[1066]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Feb 22 20:33:43 volumio volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 20:33:43 volumio volumio[1066]: TypeError: Cannot read properties of null (reading 'slice') Feb 22 20:33:43 volumio volumio[1066]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 22 20:33:43 volumio volumio[1066]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 22 20:33:43 volumio volumio[1066]: at Object.onceWrapper (node:events:629:26) Feb 22 20:33:43 volumio volumio[1066]: at ClientRequest.emit (node:events:514:28) Feb 22 20:33:43 volumio volumio[1066]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 22 20:33:43 volumio volumio[1066]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 22 20:33:43 volumio volumio[1066]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 22 20:33:43 volumio volumio[1066]: at TLSSocket.emit (node:events:514:28) Feb 22 20:33:43 volumio volumio[1066]: at addChunk (node:internal/streams/readable:343:12) Feb 22 20:33:43 volumio volumio[1066]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 22 20:33:43 volumio volumio[1066]: at Readable.push (node:internal/streams/readable:253:10) Feb 22 20:33:43 volumio volumio[1066]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Feb 22 20:33:43 volumio volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 20:33:43 volumio sudo[42573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 20:32' Feb 22 20:33:43 volumio sudo[42573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"