-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-05-25 11:26:27 CEST. -- May 25 11:25:08 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bobby%20Bare/bf222680-a089-41cd-a906-16d8a67d5a8e.jpg' May 25 11:25:08 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:25:08 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:25:08 volumio volumio[1183]: info: Discovery: Getting this device information May 25 11:25:08 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState May 25 11:25:08 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:08 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:25:09 volumio volumio[1183]: verbose: New Socket.io Connection to 192.168.178.35:3000 from 192.168.178.33 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:25:09 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Buck%20Trail/f87e389e-30e4-4392-afed-b293ae0067a9.jpg' May 25 11:25:09 volumio volumio[1183]: verbose: New Socket.io Connection to 192.168.178.35 from 192.168.178.33 UA: Mozilla/5.0 (Linux; Android 13; SM-A515F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState May 25 11:25:09 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 11:25:09 volumio volumio[1183]: info: Listing playlists May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::volumioGetVisibleSources May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 11:25:09 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 25 11:25:09 volumio volumio[1183]: info: CoreStateMachine::getQueue May 25 11:25:09 volumio volumio[1183]: info: CorePlayQueue::getQueue May 25 11:25:09 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carl%20Perkins/c99c0063-3e8c-4285-89e1-fd149fbde62e.jpg' May 25 11:25:10 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cliff%20Richards/2d56199c-c415-41bf-90c0-4487f57a52a5.jpg' May 25 11:25:14 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eddie%20Cochran/f403903f-ce88-46aa-b942-647fa9196073.jpg' May 25 11:25:14 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eddie%20Cochrane/bd8d8efd-40c4-48bc-94c9-455aaacaec66.jpg' May 25 11:25:17 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gene%20Vincent/053562fa-e7e2-438d-913b-3ff7d8ed766c.jpg' May 25 11:25:17 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Glen%20Glenn/1cd17e7e-91fa-4d79-9d2f-7798dffa1bfd.jpg' May 25 11:25:18 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jeff%20Daniels/b05c57b3-da50-4ddf-b506-7649044ba7b3.jpg' May 25 11:25:19 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jerry%20Lee%20Lewis/c66a7516-a6c7-4d6e-917d-c163d915daae.jpg' May 25 11:25:20 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jim%20Lowe/7a99b26d-cf1c-49a3-a019-26b5c78fbdd6.jpg' May 25 11:25:20 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jimmy%20Bowen/ff51c8b2-4cf3-4f15-82f6-64df49bbb051.jpg' May 25 11:25:20 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joe%20Montgomery/a36184ea-43d0-49c7-b25f-0b796a9cc6db.jpg' May 25 11:25:21 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/John%20Barry/1bc36a1c-bcc8-4012-a8b6-0dbd8aca0927.jpg' May 25 11:25:21 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Johnny%20O%27keefe/16c284f3-a623-4723-b29b-1f3154ecc7aa.jpg' May 25 11:25:22 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lew%20Williams/0434cf54-de0c-4dfd-9667-8bd7da75721d.jpg' May 25 11:25:22 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Link%20Wray/1f867923-83c1-4493-a137-08c63333c64f.jpg' May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::next May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::stPlaybackTimer May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::serviceStop May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::serviceStop May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::stop May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.614+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.614+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Brother Zee & The Decades - Sha-Boom Bang.wav" title="Sha-Boom Bang" May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand stop took 9 milliseconds May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 5 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand stop took 3 milliseconds May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 13 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand clear took 14 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 14 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 13 milliseconds May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sha-Boom Bang","artist":"Brother Zee & The Decades","album":null,"uri":"USB/Volume/Mixtape/doo wop/Brother Zee & The Decades - Sha-Boom Bang.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: No code May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: 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} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: No code May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: 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} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: No code May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.659+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.659+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.659+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.659+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.660+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.660+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 42ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 35ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 34ms May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: error: updateQueue error: null May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 25ms May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand add "USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" took 23 milliseconds May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 2ms May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:24 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:24 volumio volumio[1183]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 8ms May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand play took 8 milliseconds May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 7ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 7ms May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: May 25 11:25:24 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:24 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:24 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 9ms May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.806+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.806+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.806+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.807+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 10ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 11ms May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 11:25:24 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 11 milliseconds May 25 11:25:24 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:24 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:24 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Promise","artist":"Jimmy Castor And The Juniors","album":null,"uri":"USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav","trackType":"wav"} May 25 11:25:24 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:24 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:24 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:24 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=960 volume=100 May 25 11:25:24 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:24.818+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 22ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 21ms May 25 11:25:24 volumio volumio[1183]: info: ------------------------------ 20ms May 25 11:25:24 volumio volumio[1183]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::next May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::stPlaybackTimer May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::serviceStop May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::serviceStop May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::stop May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.541+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.541+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPlay May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::play index 1 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioRandom May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::writePlayerControls May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setRandom true May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.555+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.555+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/doo wop/Jimmy Castor And The Juniors - I Promise.wav" title="I Promise" May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/Mixtape/doo wop/Brother Zee & The Decades - Sha-Boom Bang.wav May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand stop took 18 milliseconds May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand stop took 2 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand clear took 1 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand stop took 1 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/Mixtape/doo wop/Brother Zee & The Decades - Sha-Boom Bang.wav" May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: error: updateQueue error: null May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 11ms May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 11 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand add "USB/Volume/Mixtape/doo wop/Brother Zee & The Decades - Sha-Boom Bang.wav" took 11 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand clear took 10 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: 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} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: No code May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: 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} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: No code May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 20ms May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=1 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=1 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=1 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.580+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_STOPPED positionMs=1 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.580+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 11ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 12ms May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: error: updateQueue error: null May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 12ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 12ms May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand play took 12 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand add "USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" took 12 milliseconds May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 2ms May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 25 11:25:26 volumio volumio[1183]: info: Ignoring MPD Status Update May 25 11:25:26 volumio volumio[1183]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 6ms May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand play took 6 milliseconds May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 6ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 5ms May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: May 25 11:25:26 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::getState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 101 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 101 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 100 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 100 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 99 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand status took 98 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseState May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:26 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:26 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 106ms May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.723+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.724+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.724+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.724+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.724+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.724+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 109ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 108ms May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 25 11:25:26 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:26 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:26 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: ControllerMpd::pushState May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bolivar Blues","artist":"Thelonius Monk","album":null,"uri":"USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav","trackType":"wav"} May 25 11:25:26 volumio volumio[1183]: verbose: CURRENT POSITION 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 25 11:25:26 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio[1183]: info: CoreStateMachine::pushState May 25 11:25:26 volumio volumio[1183]: info: CorePlayQueue::getTrack 1608 May 25 11:25:26 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.731+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.731+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.731+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.731+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.732+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.732+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.732+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" state=STATUS_PLAYING positionMs=869 volume=100 May 25 11:25:26 volumio volumio5-onboarding[1440]: time=2026-05-25T11:25:26.732+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.33:38908,00:00:00:00:00:00%02 @ 0x193cb70" id="mnt/USB/Volume/Mixtape/jazz/Thelonius Monk - Bolivar Blues.wav" title="Bolivar Blues" May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 116ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 114ms May 25 11:25:26 volumio volumio[1183]: info: ------------------------------ 113ms May 25 11:25:26 volumio volumio[1183]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:25:26 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Patsy%20Cline/3f5d1bd9-64e4-45d9-82ef-ace24050b6e4.jpg' May 25 11:25:27 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ray%20Smith/b89a58d8-de8b-46c3-a485-bfcc4c2d632f.jpg' May 25 11:25:27 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ricky%20Nelson/d4104b41-1db4-452d-8c0a-9949768d8d55.jpg' May 25 11:25:28 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Roy%20Orbison/81c3d06a-f195-4a71-9b36-5032fa69feb2.jpg' May 25 11:25:28 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sid%20King/13ae23d3-93a8-4785-a464-b5e1f1672d65.jpg' May 25 11:25:31 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Busters/ce6f1b5d-e1a4-4a53-a6c4-096b95e286de.jpg' May 25 11:25:31 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Crazy%20Teens/d99cc1ed-ad01-4307-835b-a5f73d0b9236.jpg' May 25 11:25:31 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Everly%20Brothers/e277f597-39b1-4ad3-bf56-b2d1da44d055.jpg' May 25 11:25:33 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Shutdowns/0b077fbd-5746-4867-8c7d-0785d22827fd.jpg' May 25 11:25:34 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Wayne%20Raney/22a96abf-7621-4583-b92e-9e33b497c521.jpg' May 25 11:25:35 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ac-Dc/e3fddbed-5992-4c51-b5a6-41e681c7438e.jpg' May 25 11:25:36 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Achim%20Reichel/95227d30-a78b-4998-9beb-45e88ee4f04f.jpg' May 25 11:25:36 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Adriano%20Celentano/64fa4b3a-362a-4ef3-b939-6033eba24f55.jpg' May 25 11:26:01 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/John%20Barry/1bc36a1c-bcc8-4012-a8b6-0dbd8aca0927.jpg' May 25 11:26:27 volumio volumio[1183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:26:27 volumio volumio[1183]: TypeError: Cannot read property 'slice' of null May 25 11:26:27 volumio volumio[1183]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 25 11:26:27 volumio volumio[1183]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 25 11:26:27 volumio volumio[1183]: at Object.onceWrapper (events.js:422:26) May 25 11:26:27 volumio volumio[1183]: at ClientRequest.emit (events.js:315:20) May 25 11:26:27 volumio volumio[1183]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) May 25 11:26:27 volumio volumio[1183]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) May 25 11:26:27 volumio volumio[1183]: at TLSSocket.socketOnData (_http_client.js:509:22) May 25 11:26:27 volumio volumio[1183]: at TLSSocket.emit (events.js:315:20) May 25 11:26:27 volumio volumio[1183]: at addChunk (internal/streams/readable.js:309:12) May 25 11:26:27 volumio volumio[1183]: at readableAddChunk (internal/streams/readable.js:284:9) May 25 11:26:27 volumio volumio[1183]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) May 25 11:26:27 volumio volumio[1183]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) May 25 11:26:27 volumio volumio[1183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:26:27 volumio sudo[18208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-25 11:25 May 25 11:26:27 volumio sudo[18208]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"