Aug 31 12:31:07 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:07.974+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.101:45256 @ 0x33c4960" latency=34.911198ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.042+03:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.185+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=http://pushupdates.volumio.org duration=141.161223ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.571+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=529.480203ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.696+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=652.627551ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.718+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=http://plugins.volumio.org duration=673.119627ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.901+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=http://cddb.volumio.org duration=858.836673ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.964+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://www.googleapis.com duration=921.381575ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.965+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://functions.volumio.cloud duration=922.42099ms Aug 31 12:31:08 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:08.991+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://functions.volumio.cloud duration=947.81619ms Aug 31 12:31:09 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:09.009+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://securetoken.googleapis.com duration=966.751942ms Aug 31 12:31:09 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:09.064+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.021897407s Aug 31 12:31:09 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:09.082+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.038670867s Aug 31 12:31:09 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:09.278+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://database.volumio.cloud duration=1.234288755s Aug 31 12:31:09 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:09.432+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.101:45256 @ 0x33c4960" latency=10.151149ms timeout=10s endpoint=https://google.com duration=1.389619958s Aug 31 12:31:18 volumio sudo[2408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 12:31:18 volumio sudo[2408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 12:31:18 volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Aug 31 12:31:18 volumio sudo[2410]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 12:31:18 volumio sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 12:31:18 volumio sudo[2410]: pam_unix(sudo:session): session closed for user root Aug 31 12:31:18 volumio volumio[1261]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 8.1.0; Redmi 5 Plus Build/OPM1.171019.019; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 12:31:19 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 12:31:19 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 12:31:20 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 12:31:20 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 12:31:20 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Aug 31 12:31:20 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Aug 31 12:31:22 volumio volumio[1261]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 8.1.0; Redmi 5 Plus Build/OPM1.171019.019; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 12:31:23 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 12:31:24 volumio volumio[1261]: info: Received Get System Info Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 12:31:24 volumio volumio[1261]: info: Discovery: Getting this device information Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:24 volumio volumio[1261]: info: Listing playlists Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 12:31:24 volumio volumio[1261]: info: Received Get System Info Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 12:31:24 volumio volumio[1261]: info: Discovery: Getting this device information Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 12:31:25 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 12:31:26 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 12:31:26 volumio volumio[1261]: info: Received Get System Info Aug 31 12:31:26 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 12:31:26 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 12:31:26 volumio volumio[1261]: info: Discovery: Getting this device information Aug 31 12:31:26 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:26 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 12:31:31 volumio volumio[1261]: info: CoreCommandRouter::volumioGetQueue Aug 31 12:31:31 volumio volumio[1261]: info: CoreStateMachine::getQueue Aug 31 12:31:31 volumio volumio[1261]: info: CorePlayQueue::getQueue Aug 31 12:31:33 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 12:31:37 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:31:37 volumio volumio[1261]: info: CURURI: music-library Aug 31 12:31:37 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 12:31:38 volumio volumio[1261]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 12:31:38 volumio volumio[1261]: info: Received Get System Version Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 12:31:38 volumio volumio[1261]: info: Received Get System Info Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 12:31:38 volumio volumio[1261]: info: Discovery: Getting this device information Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState Aug 31 12:31:38 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:38 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 12:31:39 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand update Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: info: Aug 31 12:31:39 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand update took 9 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: info: Aug 31 12:31:39 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:31:39 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: info: Aug 31 12:31:39 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:31:39 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 20 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 6 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 5 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 4 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 4 milliseconds Aug 31 12:31:39 volumio volumio[1261]: info: sendMpdCommand status took 3 milliseconds Aug 31 12:31:39 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: info: Command Router : Notfying DB Updatetrue Aug 31 12:31:40 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: info: Command Router : Notfying DB Updatetrue Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:31:40 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: info: Command Router : Notfying DB Updatetrue Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:31:40 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:31:40 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:31:40 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: No code Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:31:40 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: No code Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:31:40 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio[1261]: info: No code Aug 31 12:31:40 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:31:40 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:31:40 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.902+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.903+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.904+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.904+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.912+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.912+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.914+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.914+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.922+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.922+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.924+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.924+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.953+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.954+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.956+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.956+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.963+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:31:40 volumio volumio5-onboarding[1589]: time=2026-08-31T12:31:40.963+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Vinyl/1996 - Cyanide Music Volume One (Vinyl) (CYAN 001)/A1. Chicane - Be That Lover.flac" title="Be That Lover" Aug 31 12:31:41 volumio volumio[1261]: info: ------------------------------ 1397ms Aug 31 12:31:41 volumio volumio[1261]: info: ------------------------------ 1383ms Aug 31 12:31:41 volumio volumio[1261]: info: ------------------------------ 1466ms Aug 31 12:31:54 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:31:54 volumio volumio[1261]: info: CURURI: music-library/USB Aug 31 12:31:54 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:31:57 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:31:57 volumio volumio[1261]: info: CURURI: music-library/USB/B268C64A806044EB Aug 31 12:31:57 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:16 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:32:16 volumio volumio[1261]: info: CURURI: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979) Aug 31 12:32:16 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:16 volumio volumio[1261]: info: Preloading song: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F01%20-%20Ain't%20Nothin'%20Shakin'.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F02%20-%20Scat%20Encounter.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F03%20-%20Hey%20Joe.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F04%20-%20Going%20Home.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F05%20-%20Too%20Much.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F06%20-%20It's%20A%20Gaz.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf in service mpd Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F07%20-%20Ride%20On%20Cowboy.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F08%20-%20Sittin'%20Here.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:17 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=Alvin%20Lee%20%26%20Ten%20Years%20Later/Ride%20On%2C%201979/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAlvin%20Lee%20%26%20Ten%20Years%20Later%20%E2%80%93%20Ride%20On%20-%20(1979)%2F09%20-%20Can't%20Sleep%20At%20Nite.dsf&metadata=false Aug 31 12:32:17 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:18 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:18 volumio volumio[1261]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 12:32:18 volumio volumio[1261]: info: CoreStateMachine::ClearQueue Aug 31 12:32:18 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:18 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:18 volumio volumio[1261]: info: CorePlayQueue::clearPlayQueue Aug 31 12:32:18 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:18 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:18 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:18 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:18 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:18 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:18 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:18 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPlay Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::play index 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:19 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:19 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers Aug 31 12:32:19 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers in service mpd Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::play index undefined Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::startPlaybackTimer Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::clearAddPlayTracks USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand stop took 11 milliseconds Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand clear took 111 milliseconds Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: error: updateQueue error: null Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 62ms Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" took 61 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 60ms Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 59ms Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand play Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:19 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 45ms Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand play took 43 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 20ms Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 19ms Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 57 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 75 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 47 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 47 milliseconds Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: Aug 31 12:32:19 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 91 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 90 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 49 milliseconds Aug 31 12:32:19 volumio volumio[1261]: info: sendMpdCommand status took 40 milliseconds Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:19 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:19 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:19 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:19 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:19 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:19 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:19 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:19 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:19 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:19 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:19 volumio volumio[1261]: info: ------------------------------ 324ms Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.925+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.926+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.971+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.972+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.972+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.972+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.973+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.974+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.975+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.975+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.976+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 12:32:19 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:19.976+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:20 volumio volumio[1261]: info: ------------------------------ 743ms Aug 31 12:32:20 volumio volumio[1261]: info: ------------------------------ 716ms Aug 31 12:32:20 volumio volumio[1261]: info: ------------------------------ 716ms Aug 31 12:32:20 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 589 milliseconds Aug 31 12:32:20 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 590 milliseconds Aug 31 12:32:20 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:20 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:20 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:20 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:20 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:20 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:20 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:20 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:20 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:20 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:20 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.473+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=994 volume=100 Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.473+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.474+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=994 volume=100 Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.474+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.474+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.475+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.475+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:20 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:20.475+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:20 volumio volumio[1261]: info: ------------------------------ 955ms Aug 31 12:32:20 volumio volumio[1261]: info: ------------------------------ 955ms Aug 31 12:32:21 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::ClearQueue Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::serviceStop Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::serviceStop Aug 31 12:32:21 volumio volumio[1261]: info: ControllerMpd::stop Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::clearPlayQueue Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:21 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:21 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:21.473+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:32:21 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:21.473+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioPlay Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::play index 1 Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:21 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:21 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers Aug 31 12:32:21 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:21 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::play index undefined Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:21 volumio volumio[1261]: info: CoreStateMachine::startPlaybackTimer Aug 31 12:32:21 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::clearAddPlayTracks USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:21 volumio volumio[1261]: info: Aug 31 12:32:21 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand stop took 433 milliseconds Aug 31 12:32:21 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand stop took 214 milliseconds Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 12:32:21 volumio volumio[1261]: info: Aug 31 12:32:21 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:21 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:21 volumio volumio[1261]: info: Aug 31 12:32:21 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:21 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:21 volumio volumio[1261]: info: Aug 31 12:32:21 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:21 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand status took 93 milliseconds Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand clear took 93 milliseconds Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand status took 71 milliseconds Aug 31 12:32:21 volumio volumio[1261]: info: sendMpdCommand status took 70 milliseconds Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:21 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:22 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:32:22 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio[1261]: info: No code Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:32:22 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio[1261]: info: No code Aug 31 12:32:22 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:22 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:22 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.224+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.224+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.225+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.226+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.226+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.227+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.228+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.228+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.263+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.263+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.264+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=51 volume=100 Aug 31 12:32:22 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:22.264+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 633ms Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 658ms Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:22 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:22 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:22 volumio volumio[1261]: error: updateQueue error: null Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:22 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 673ms Aug 31 12:32:22 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 672 milliseconds Aug 31 12:32:22 volumio volumio[1261]: info: sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" took 673 milliseconds Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 86ms Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 96ms Aug 31 12:32:22 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:22 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand play Aug 31 12:32:22 volumio volumio[1261]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 783ms Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:22 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:22 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:22 volumio volumio[1261]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 12:32:22 volumio volumio[1261]: info: Aug 31 12:32:22 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:22 volumio volumio[1261]: info: ------------------------------ 348ms Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand play took 357 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: ------------------------------ 327ms Aug 31 12:32:23 volumio volumio[1261]: info: ------------------------------ 324ms Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: Aug 31 12:32:23 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: Aug 31 12:32:23 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: Aug 31 12:32:23 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 61 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 51 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 49 milliseconds Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: info: Aug 31 12:32:23 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: Aug 31 12:32:23 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 68 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 67 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 68 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 68 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 68 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand status took 84 milliseconds Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1246,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:23 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1246,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:23 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: ------------------------------ 387ms Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.395+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.395+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.414+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.414+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.415+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.415+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.444+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.448+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio[1261]: info: ------------------------------ 557ms Aug 31 12:32:23 volumio volumio[1261]: info: ------------------------------ 578ms Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 480 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 457 milliseconds Aug 31 12:32:23 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 457 milliseconds Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1246,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:23 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1246,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:23 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1246,"duration":58,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Scat Encounter","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf","trackType":"dsf"} Aug 31 12:32:23 volumio volumio[1261]: verbose: CURRENT POSITION 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:23 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:23 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:23 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.788+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.789+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.831+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.832+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.832+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.833+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.833+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.831+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.835+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.836+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1246 volume=100 Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.837+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:23 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:23.837+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:24 volumio volumio[1261]: info: ------------------------------ 966ms Aug 31 12:32:24 volumio volumio[1261]: info: ------------------------------ 1088ms Aug 31 12:32:24 volumio volumio[1261]: info: ------------------------------ 1088ms Aug 31 12:32:24 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::ClearQueue Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::serviceStop Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 1 Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::serviceStop Aug 31 12:32:24 volumio volumio[1261]: info: ControllerMpd::stop Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::clearPlayQueue Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:24 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:24 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:24.665+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:32:24 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:24.666+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf" title="Scat Encounter" Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioPlay Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::play index 0 Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:24 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/02 - Scat Encounter.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/03 - Hey Joe.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/04 - Going Home.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/05 - Too Much.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/06 - It's A Gaz.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/07 - Ride On Cowboy.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/08 - Sittin' Here.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/09 - Can't Sleep At Nite.dsf Aug 31 12:32:24 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers Aug 31 12:32:24 volumio volumio[1261]: info: Using cached record of: music-library/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/Covers Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:24 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::play index undefined Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:24 volumio volumio[1261]: info: CoreStateMachine::startPlaybackTimer Aug 31 12:32:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::clearAddPlayTracks USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:24 volumio volumio[1261]: info: Aug 31 12:32:24 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:24 volumio volumio[1261]: info: sendMpdCommand stop took 316 milliseconds Aug 31 12:32:24 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:24 volumio volumio[1261]: info: sendMpdCommand stop took 125 milliseconds Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 12:32:24 volumio volumio[1261]: info: Aug 31 12:32:24 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:24 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:24 volumio volumio[1261]: info: Aug 31 12:32:24 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:24 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:24 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 71 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand clear took 71 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 71 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 70 milliseconds Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: No code Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService stop Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: No code Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.322+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.322+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.323+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.324+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.325+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.325+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.332+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.333+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.334+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.335+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.352+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=6 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.353+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 511ms Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 511ms Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: error: updateQueue error: null Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 492ms Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 489 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand add "USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" took 489 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 51ms Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 50ms Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand play Aug 31 12:32:25 volumio volumio[1261]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 646ms Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces system playlist update Aug 31 12:32:25 volumio volumio[1261]: info: Ignoring MPD Status Update Aug 31 12:32:25 volumio volumio[1261]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 158ms Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand play took 158 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 157ms Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 156ms Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 3 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: Aug 31 12:32:25 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 4 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 3 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:25 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:25 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 165ms Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.846+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.846+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.847+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.847+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.848+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.848+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.849+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:25 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:25.849+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 255ms Aug 31 12:32:25 volumio volumio[1261]: info: ------------------------------ 254ms Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 271 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 219 milliseconds Aug 31 12:32:25 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 220 milliseconds Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:25 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:25 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:25 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:25 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:25 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:25 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:26 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:26 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:26 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:26 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:26 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:26 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.069+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.069+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.082+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.083+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.099+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.099+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=1120 volume=100 Aug 31 12:32:26 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:26.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:26 volumio volumio[1261]: info: ------------------------------ 514ms Aug 31 12:32:26 volumio volumio[1261]: info: ------------------------------ 512ms Aug 31 12:32:26 volumio volumio[1261]: info: ------------------------------ 511ms Aug 31 12:32:32 volumio volumio[1261]: info: MPD Database updated - AlbumList cache refreshed Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: database Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: MPD Database updated - AlbumList cache refreshed Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: database Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: MPD Database updated - AlbumList cache refreshed Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: database Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: Aug 31 12:32:32 volumio volumio[1261]: ---------------------------- MPD announces state update: update Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:32 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:32 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:33 volumio volumio[1261]: info: sendMpdCommand status took 943 milliseconds Aug 31 12:32:33 volumio volumio[1261]: info: sendMpdCommand status took 943 milliseconds Aug 31 12:32:33 volumio volumio[1261]: info: sendMpdCommand status took 943 milliseconds Aug 31 12:32:33 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:33 volumio volumio[1261]: info: Command Router : Notfying DB Updatefalse Aug 31 12:32:33 volumio volumio[1261]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:32:33 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:33 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:33 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:33 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:34 volumio volumio[1261]: info: sendMpdCommand status took 1548 milliseconds Aug 31 12:32:34 volumio volumio[1261]: info: sendMpdCommand status took 1547 milliseconds Aug 31 12:32:34 volumio volumio[1261]: info: sendMpdCommand status took 1547 milliseconds Aug 31 12:32:34 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:34 volumio volumio[1261]: info: Command Router : Notfying DB Updatefalse Aug 31 12:32:34 volumio volumio[1261]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:32:34 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:34 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:34 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:34 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand status took 2100 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand status took 2099 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand status took 2100 milliseconds Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:35 volumio volumio[1261]: info: Command Router : Notfying DB Updatefalse Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseState Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 1091 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 1092 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 556 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 555 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 12:32:35 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8496,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8496,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8619,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8619,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8745,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: ControllerMpd::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::servicePushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8745,"duration":332,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Ain't Nothin' Shakin'","artist":"Alvin Lee & Ten Years Later","album":"Ride On, 1979","uri":"USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf","trackType":"dsf"} Aug 31 12:32:35 volumio volumio[1261]: verbose: CURRENT POSITION 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState stateService play Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:32:35 volumio volumio[1261]: info: Received an update from plugin. extracting info from payload Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:35 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:35 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.109+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8496 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.109+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.111+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8496 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.111+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.112+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8496 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.113+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.113+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8496 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.114+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.115+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8619 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.116+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.116+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8619 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.116+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.117+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8619 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.118+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.119+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8619 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.119+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.120+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8745 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.120+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.121+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8745 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.122+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.122+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8745 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.122+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.124+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_PLAYING positionMs=8745 volume=100 Aug 31 12:32:35 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:35.124+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2268ms Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2269ms Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2267ms Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2268ms Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2266ms Aug 31 12:32:35 volumio volumio[1261]: info: ------------------------------ 2267ms Aug 31 12:32:37 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:32:37 volumio volumio[1261]: info: CURURI: music-library/USB/B268C64A806044EB/Anthrax-1 Aug 31 12:32:37 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:41 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 12:32:41 volumio volumio[1261]: info: CURURI: music-library/USB/B268C64A806044EB/Anthrax-1/1985 - Armed And Dangerous [CAROL 1382] (US Reissue, NM, AT33PTGII) shinigamihand release Aug 31 12:32:41 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:51 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:51 volumio volumio[1261]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::ClearQueue Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::stop Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::updateTrackBlock Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::getTrackBlock Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::pushState Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:51 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:32:51 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::serviceStop Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::getTrack 0 Aug 31 12:32:51 volumio volumio[1261]: info: CoreCommandRouter::serviceStop Aug 31 12:32:51 volumio volumio[1261]: info: ControllerMpd::stop Aug 31 12:32:51 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::clearPlayQueue Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::saveQueue Aug 31 12:32:51 volumio volumio[1261]: info: CoreCommandRouter::volumioPushQueue Aug 31 12:32:51 volumio volumio[1261]: info: CoreStateMachine::addQueueItems Aug 31 12:32:51 volumio volumio[1261]: info: CorePlayQueue::addQueueItems Aug 31 12:32:51 volumio volumio[1261]: info: Preload queue cleared Aug 31 12:32:51 volumio volumio[1261]: info: Adding Item to queue: music-library/USB/B268C64A806044EB/Anthrax-1/1985 - Armed And Dangerous [CAROL 1382] (US Reissue, NM, AT33PTGII) shinigamihand release Aug 31 12:32:51 volumio volumio[1261]: info: Exploding uri music-library/USB/B268C64A806044EB/Anthrax-1/1985 - Armed And Dangerous [CAROL 1382] (US Reissue, NM, AT33PTGII) shinigamihand release in service mpd Aug 31 12:32:51 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:51.720+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 12:32:51 volumio volumio5-onboarding[1589]: time=2026-08-31T12:32:51.720+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.101:45256 @ 0x33c4960" id="mnt/USB/B268C64A806044EB/Alvin Lee & Ten Years Later – Ride On - (1979)/01 - Ain't Nothin' Shakin'.dsf" title="Ain't Nothin' Shakin'" Aug 31 12:32:51 volumio volumio[1261]: info: sendMpdCommand stop took 32 milliseconds Aug 31 12:32:51 volumio volumio[1261]: info: Aug 31 12:32:51 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:51 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:51 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:51 volumio volumio[1261]: info: Aug 31 12:32:51 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:51 volumio volumio[1261]: info: ControllerMpd::getState Aug 31 12:32:51 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:32:51 volumio volumio[1261]: info: Aug 31 12:32:51 volumio volumio[1261]: ---------------------------- MPD announces state update: player Aug 31 12:32:51 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=/Armed%20And%20Dangerous/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAnthrax-1%2F1985%20-%20Armed%20And%20Dangerous%20%5BCAROL%201382%5D%20(US%20Reissue%2C%20NM%2C%20AT33PTGII)%20shinigamihand%20release%2FAnthrax%20-%20Armed%20And%20Dangerous.cue&metadata=false Aug 31 12:32:51 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Anthrax-1/1985 - Armed And Dangerous [CAROL 1382] (US Reissue, NM, AT33PTGII) shinigamihand release/Anthrax - Armed And Dangerous.cue Aug 31 12:32:51 volumio volumio[1261]: info: ALBUMART /albumart?cacheid=602&web=/Armed%20And%20Dangerous/extralarge&path=%2Fmnt%2FUSB%2FB268C64A806044EB%2FAnthrax-1%2F1985%20-%20Armed%20And%20Dangerous%20%5BCAROL%201382%5D%20(US%20Reissue%2C%20NM%2C%20AT33PTGII)%20shinigamihand%20release%2FAnthrax%20-%20Armed%20And%20Dangerous.cue&metadata=false Aug 31 12:32:51 volumio volumio[1261]: info: URI /mnt/USB/B268C64A806044EB/Anthrax-1/1985 - Armed And Dangerous [CAROL 1382] (US Reissue, NM, AT33PTGII) shinigamihand release/Anthrax - Armed And Dangerous.cue Aug 31 12:32:51 volumio volumio[1261]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:32:51 volumio volumio[1261]: Error: Unable to resolve or reject the same promise twice Aug 31 12:32:51 volumio volumio[1261]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 31 12:32:51 volumio volumio[1261]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 31 12:32:51 volumio volumio[1261]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 31 12:32:51 volumio volumio[1261]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 31 12:32:51 volumio volumio[1261]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 31 12:32:51 volumio volumio[1261]: at Socket.emit (node:events:514:28) Aug 31 12:32:51 volumio volumio[1261]: at addChunk (node:internal/streams/readable:343:12) Aug 31 12:32:51 volumio volumio[1261]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 31 12:32:51 volumio volumio[1261]: at Readable.push (node:internal/streams/readable:253:10) Aug 31 12:32:51 volumio volumio[1261]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 31 12:32:51 volumio volumio[1261]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:32:52 volumio sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 12:31' Aug 31 12:32:52 volumio sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"