Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.107Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 23:15:01 dell volumio[1070]: info: Discovery: Getting this device information Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 29 23:15:01 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.197Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=http://pushupdates.volumio.org duration=89.832571ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.213Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=105.298869ms Aug 29 23:15:01 dell volumio[1070]: verbose: New Socket.io Connection to 192.168.50.73:3000 from 192.168.50.185 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 23:15:01 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.260Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=152.769776ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.262Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=154.494061ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.318Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://www.googleapis.com duration=210.46375ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.364Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://securetoken.googleapis.com duration=256.881132ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.379Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://functions.volumio.cloud duration=272.130043ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.380Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://functions.volumio.cloud duration=272.274157ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.500Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=392.381648ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.543Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=http://cddb.volumio.org duration=436.03195ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.607Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://database.volumio.cloud duration=498.636299ms Aug 29 23:15:01 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:01.632Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=https://google.com duration=524.637587ms Aug 29 23:15:02 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:02.079Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-40.329028ms timeout=10s endpoint=http://plugins.volumio.org duration=971.01735ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.084Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 23:15:03 dell volumio[1070]: info: Discovery: Getting this device information Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 29 23:15:03 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.112Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=28.055943ms Aug 29 23:15:03 dell volumio[1070]: verbose: New Socket.io Connection to 192.168.50.73:3000 from 192.168.50.185 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 23:15:03 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.126Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=http://pushupdates.volumio.org duration=41.928389ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.129Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=45.119674ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.142Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=58.454118ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.219Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://www.googleapis.com duration=135.255977ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.255Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=171.371658ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.257Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://securetoken.googleapis.com duration=173.544699ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.285Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://functions.volumio.cloud duration=201.297612ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.286Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://database.volumio.cloud duration=201.796414ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.292Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://functions.volumio.cloud duration=207.748848ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.300Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=http://cddb.volumio.org duration=216.688702ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.451Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=http://plugins.volumio.org duration=366.881234ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.471Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-41.9984ms timeout=10s endpoint=https://google.com duration=387.590138ms Aug 29 23:15:03 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:03.996Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 23:15:04 dell volumio[1070]: info: Discovery: Getting this device information Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 29 23:15:04 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 23:15:04 dell volumio[1070]: verbose: New Socket.io Connection to 192.168.50.73:3000 from 192.168.50.185 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.024Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=28.390127ms Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 23:15:04 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.036Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=39.368981ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.038Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=http://pushupdates.volumio.org duration=42.198951ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.047Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=50.500634ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.131Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://www.googleapis.com duration=134.827193ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.170Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://securetoken.googleapis.com duration=173.582662ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.175Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=178.511215ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.198Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://database.volumio.cloud duration=201.245307ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.209Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://functions.volumio.cloud duration=213.071567ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.210Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://functions.volumio.cloud duration=213.780961ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.213Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=http://cddb.volumio.org duration=217.002058ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.370Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=http://plugins.volumio.org duration=373.897635ms Aug 29 23:15:04 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:04.378Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-37.497277ms timeout=10s endpoint=https://google.com duration=382.13176ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.381Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.411Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=29.427348ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.421Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=39.963278ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.425Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=http://pushupdates.volumio.org duration=43.74604ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.440Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=58.680603ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.511Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://www.googleapis.com duration=129.586373ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.548Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=166.643145ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.555Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://securetoken.googleapis.com duration=173.203021ms Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 23:15:18 dell volumio[1070]: info: Discovery: Getting this device information Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 29 23:15:18 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.581Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://database.volumio.cloud duration=199.725959ms Aug 29 23:15:18 dell volumio[1070]: verbose: New Socket.io Connection to 192.168.50.73:3000 from 192.168.50.185 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.586Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://functions.volumio.cloud duration=204.879799ms Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 23:15:18 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.596Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://functions.volumio.cloud duration=214.434108ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.598Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=http://cddb.volumio.org duration=216.255133ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.769Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=https://google.com duration=387.480497ms Aug 29 23:15:18 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:18.936Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.185:54242 @ 0xc000393560" latency=-51.285155ms timeout=10s endpoint=http://plugins.volumio.org duration=554.195291ms Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioNext Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::next Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 62 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.131Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.131Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/66Dean Martin - Besame Mucho.MP3" title="Besame Mucho" Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand stop took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::play index undefined Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3 Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand stop took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Besame Mucho","artist":"Dean Martin","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/66Dean Martin - Besame Mucho.MP3","trackType":"MP3"} Aug 29 23:15:19 dell volumio[1070]: verbose: CURRENT POSITION 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: No code Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 6ms Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:19 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand clear took 6 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 8ms Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:19 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:19 dell volumio[1070]: error: updateQueue error: null Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:19 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 2ms Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" took 2 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:19 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand play took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: Aug 29 23:15:19 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 4 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand status took 3 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Un-break My Heart","artist":"Tony Braxton","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3","trackType":"MP3"} Aug 29 23:15:19 dell volumio[1070]: verbose: CURRENT POSITION 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Un-break My Heart","artist":"Tony Braxton","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3","trackType":"MP3"} Aug 29 23:15:19 dell volumio[1070]: verbose: CURRENT POSITION 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:19 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 7ms Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.298Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.299Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.299Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.299Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 10ms Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 29 23:15:19 dell volumio[1070]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Un-break My Heart","artist":"Tony Braxton","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3","trackType":"MP3"} Aug 29 23:15:19 dell volumio[1070]: verbose: CURRENT POSITION 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:19 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Un-break My Heart","artist":"Tony Braxton","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3","trackType":"MP3"} Aug 29 23:15:19 dell volumio[1070]: verbose: CURRENT POSITION 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:19 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:19 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:19 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:19.305Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 14ms Aug 29 23:15:19 dell volumio[1070]: info: ------------------------------ 14ms Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioNext Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::next Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 63 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:15:21 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.885Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.885Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3" title="Un-break My Heart" Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand stop took 2 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::play index undefined Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3 Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand stop took 0 milliseconds Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:21 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:21 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand clear took 0 milliseconds Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" Aug 29 23:15:21 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Un-break My Heart","artist":"Tony Braxton","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/67Tony Braxton - Un-break My Heart.MP3","trackType":"MP3"} Aug 29 23:15:21 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio[1070]: info: No code Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.890Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 5ms Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:21 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:21 dell volumio[1070]: error: updateQueue error: null Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:21 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand status took 4 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" took 4 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:21 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 29 23:15:21 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 29 23:15:21 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio[1070]: info: No code Aug 29 23:15:21 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:21 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:21 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=5 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=5 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=5 volume=100 Aug 29 23:15:21 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:21.896Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 9ms Aug 29 23:15:21 dell volumio[1070]: info: Aug 29 23:15:21 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:21 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 7ms Aug 29 23:15:21 dell volumio[1070]: info: sendMpdCommand play took 7 milliseconds Aug 29 23:15:21 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:22 dell volumio[1070]: info: Aug 29 23:15:22 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:22 dell volumio[1070]: info: Aug 29 23:15:22 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:22 dell volumio[1070]: info: Aug 29 23:15:22 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:22 dell volumio[1070]: info: Aug 29 23:15:22 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Henry Lee","artist":"Nick Cave & PJ Harvey","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3","trackType":"MP3"} Aug 29 23:15:22 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Henry Lee","artist":"Nick Cave & PJ Harvey","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3","trackType":"MP3"} Aug 29 23:15:22 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:22 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.017Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.017Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.017Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.017Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio[1070]: info: ------------------------------ 5ms Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:22 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:22 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Henry Lee","artist":"Nick Cave & PJ Harvey","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3","trackType":"MP3"} Aug 29 23:15:22 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:22 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Henry Lee","artist":"Nick Cave & PJ Harvey","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3","trackType":"MP3"} Aug 29 23:15:22 dell volumio[1070]: verbose: CURRENT POSITION 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:22 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:22 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:22 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.022Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.022Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.022Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.022Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:22 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:22.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:22 dell volumio[1070]: info: ------------------------------ 10ms Aug 29 23:15:22 dell volumio[1070]: info: ------------------------------ 10ms Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPlay Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::play index 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 64 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.678Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.678Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3" title="Henry Lee" Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand stop took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::play index undefined Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3 Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand stop took 0 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:35 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand clear took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Henry Lee","artist":"Nick Cave & PJ Harvey","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/68Nick Cave & PJ Harvey - Henry Lee.MP3","trackType":"MP3"} Aug 29 23:15:35 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: No code Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.683Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 5ms Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:35 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:35 dell volumio[1070]: error: updateQueue error: null Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:35 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" took 3 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 0ms Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:35 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 1ms Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand play took 2 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 2ms Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: Aug 29 23:15:35 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:35 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:35 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:35 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.829Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.829Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.829Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.829Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:35 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:35 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:35 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:35 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:35 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:35 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:35 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.834Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.834Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:35 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:35.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 9ms Aug 29 23:15:35 dell volumio[1070]: info: ------------------------------ 10ms Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioSeek Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::seek Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: TRACKBLOCK {"uri":"mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","service":"mpd","name":"Des Mensonges En Musique","artist":"Patricia Kaas","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=660&web=Patricia%20Kaas//extralarge&path=%2FNAS%2FMedia%2F%D0%9D%D0%BE%D0%B2%D0%B0%D1%8F%20%D0%BF%D0%B0%D0%BF%D0%BA%D0%B0%2FGolden%20Ballads&metadata=false","duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"MP3","channels":2} Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: ControllerMpd::seek Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.821Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=106000 volume=100 Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.821Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:37 dell volumio[1070]: error: null Aug 29 23:15:37 dell volumio[1070]: info: Aug 29 23:15:37 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:37 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:37 dell volumio[1070]: info: Aug 29 23:15:37 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:37 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:37 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:37 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:37 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:37 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106000,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:37 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:37 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=106000 volume=100 Aug 29 23:15:37 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:15:37 dell volumio[1070]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 23:15:37 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:37 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=106000 volume=100 Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:37 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106000,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:37 dell volumio[1070]: verbose: CURRENT POSITION 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:37 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:37 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:37 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:37 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.837Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=106000 volume=100 Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.837Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=106000 volume=100 Aug 29 23:15:37 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:37.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:37 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPlay Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::play index 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 70 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.692Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.693Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3" title="Des Mensonges En Musique" Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand stop took 2 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::play index undefined Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3 Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand stop took 0 milliseconds Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:46 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand clear took 0 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Des Mensonges En Musique","artist":"Patricia Kaas","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/78Patricia Kaas - Des Mensonges En Musique.MP3","trackType":"MP3"} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: No code Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: No code Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.697Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.698Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 5ms Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:46 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:46 dell volumio[1070]: error: updateQueue error: null Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:46 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" took 4 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 0ms Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:15:46 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 2ms Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand play took 2 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: Aug 29 23:15:46 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 2 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 2 milliseconds Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:46 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 6ms Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.841Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.841Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.841Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 6ms Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:15:46 dell volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:46 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:46 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:46 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:46 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:46 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:46 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.847Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:15:46 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:46.848Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 12ms Aug 29 23:15:46 dell volumio[1070]: info: ------------------------------ 12ms Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioSeek Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::seek Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: TRACKBLOCK {"uri":"mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","service":"mpd","name":"Who's Gonna Stop The Rain","artist":"Anastasia","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=660&web=Anastasia//extralarge&path=%2FNAS%2FMedia%2F%D0%9D%D0%BE%D0%B2%D0%B0%D1%8F%20%D0%BF%D0%B0%D0%BF%D0%BA%D0%B0%2FGolden%20Ballads&metadata=false","duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"mp3","channels":2} Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: ControllerMpd::seek Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.242Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=141000 volume=100 Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.242Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:48 dell volumio[1070]: error: null Aug 29 23:15:48 dell volumio[1070]: info: Aug 29 23:15:48 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:48 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:48 dell volumio[1070]: info: Aug 29 23:15:48 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:15:48 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:15:48 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:48 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:15:48 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:15:48 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:48 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:15:48 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:48 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:48 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:48 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:15:48 dell volumio[1070]: verbose: CURRENT POSITION 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:15:48 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:48 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:15:48 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:15:48 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=141000 volume=100 Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=141000 volume=100 Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=141000 volume=100 Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.324Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.325Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=141000 volume=100 Aug 29 23:15:48 dell volumio5-onboarding[1597]: time=2026-08-29T20:15:48.325Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:15:48 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:48 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:15:56 dell volumio[1070]: info: CoreCommandRouter::volumioMoveQueue Aug 29 23:15:56 dell volumio[1070]: info: CoreStateMachine::moveQueueItem 86 84 Aug 29 23:15:56 dell volumio[1070]: info: CorePlayQueue::moveQueueItem 86 --> 84 Aug 29 23:15:56 dell volumio[1070]: info: CoreCommandRouter::volumioPushQueue Aug 29 23:15:59 dell volumio[1070]: info: CoreCommandRouter::volumioMoveQueue Aug 29 23:15:59 dell volumio[1070]: info: CoreStateMachine::moveQueueItem 83 83 Aug 29 23:15:59 dell volumio[1070]: info: CorePlayQueue::moveQueueItem 83 --> 83 Aug 29 23:15:59 dell volumio[1070]: info: CoreCommandRouter::volumioPushQueue Aug 29 23:16:05 dell volumio[1070]: info: CoreCommandRouter::volumioMoveQueue Aug 29 23:16:05 dell volumio[1070]: info: CoreStateMachine::moveQueueItem 86 84 Aug 29 23:16:05 dell volumio[1070]: info: CorePlayQueue::moveQueueItem 86 --> 84 Aug 29 23:16:05 dell volumio[1070]: info: CoreCommandRouter::volumioPushQueue Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPlay Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::play index 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 76 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.730Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.730Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3" title="Who's Gonna Stop The Rain" Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand stop took 11 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::play index undefined Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3 Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand stop took 2 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:16:13 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand clear took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Who's Gonna Stop The Rain","artist":"Anastasia","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/84Anastasia - Who's Gonna Stop The Rain.mp3","trackType":"mp3"} Aug 29 23:16:13 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState stateService stop Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: No code Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 5ms Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:16:13 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:16:13 dell volumio[1070]: error: updateQueue error: null Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:16:13 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand add "NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" took 4 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces system playlist update Aug 29 23:16:13 dell volumio[1070]: info: Ignoring MPD Status Update Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.745Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand play took 3 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: Aug 29 23:16:13 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand status took 1 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:13 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:13 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:16:13 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.889Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.889Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 4ms Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.889Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.889Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 23:16:13 dell volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:13 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:16:13 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:13 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:16:13 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:13 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:13 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=844 volume=100 Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:13.892Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 8ms Aug 29 23:16:13 dell volumio[1070]: info: ------------------------------ 9ms Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioSeek Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::seek Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: TRACKBLOCK {"uri":"mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","service":"mpd","name":"It Feel So Good","artist":"Sonique","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=660&web=Sonique//extralarge&path=%2FNAS%2FMedia%2F%D0%9D%D0%BE%D0%B2%D0%B0%D1%8F%20%D0%BF%D0%B0%D0%BF%D0%BA%D0%B0%2FGolden%20Ballads&metadata=false","duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"MP3","channels":2} Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: ControllerMpd::seek Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.125Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=158000 volume=100 Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.125Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:15 dell volumio[1070]: error: null Aug 29 23:16:15 dell volumio[1070]: info: Aug 29 23:16:15 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:15 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:15 dell volumio[1070]: info: Aug 29 23:16:15 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:15 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:15 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:15 dell volumio[1070]: info: sendMpdCommand status took 0 milliseconds Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::parseState Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 23:16:15 dell volumio[1070]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:15 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158000,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:15 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:16:15 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.191Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=158000 volume=100 Aug 29 23:16:15 dell volumio[1070]: info: ------------------------------ 3ms Aug 29 23:16:15 dell volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 23:16:15 dell volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.192Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=158000 volume=100 Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:15 dell volumio[1070]: info: ControllerMpd::pushState Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::servicePushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158000,"duration":339,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It Feel So Good","artist":"Sonique","album":null,"uri":"NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3","trackType":"MP3"} Aug 29 23:16:15 dell volumio[1070]: verbose: CURRENT POSITION 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 29 23:16:15 dell volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:15 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:15 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:15 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.195Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=158000 volume=100 Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.195Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_PLAYING positionMs=158000 volume=100 Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.195Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:15 dell volumio[1070]: info: ------------------------------ 6ms Aug 29 23:16:15 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:15.196Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:26 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 23:16:26 dell volumio[1070]: info: CURURI: music-library/NAS/Media/Новая папка/Good Looking Records - Producer 01-07 [FLAC] Aug 29 23:16:26 dell volumio[1070]: info: Preload queue cleared Aug 29 23:16:40 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 23:16:40 dell volumio[1070]: info: CURURI: music-library/NAS/Media/Новая папка/Music Aug 29 23:16:40 dell volumio[1070]: info: Preload queue cleared Aug 29 23:16:46 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 23:16:46 dell volumio[1070]: info: CURURI: music-library/NAS/Media/Новая папка/Rock Classics from the 80`s Aug 29 23:16:46 dell volumio[1070]: info: Preload queue cleared Aug 29 23:16:51 dell volumio[1070]: info: Preload queue cleared Aug 29 23:16:51 dell volumio[1070]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::ClearQueue Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::stop Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::stPlaybackTimer Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::updateTrackBlock Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::getTrackBlock Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::pushState Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:51 dell volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 23:16:51 dell volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::serviceStop Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::getTrack 84 Aug 29 23:16:51 dell volumio[1070]: info: CoreCommandRouter::serviceStop Aug 29 23:16:51 dell volumio[1070]: info: ControllerMpd::stop Aug 29 23:16:51 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::clearPlayQueue Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::saveQueue Aug 29 23:16:51 dell volumio[1070]: info: CoreCommandRouter::volumioPushQueue Aug 29 23:16:51 dell volumio[1070]: info: CoreStateMachine::addQueueItems Aug 29 23:16:51 dell volumio[1070]: info: CorePlayQueue::addQueueItems Aug 29 23:16:51 dell volumio[1070]: info: Preload queue cleared Aug 29 23:16:51 dell volumio[1070]: info: Adding Item to queue: music-library/NAS/Media/Новая папка/Rock Classics from the 80`s Aug 29 23:16:51 dell volumio[1070]: info: Exploding uri music-library/NAS/Media/Новая папка/Rock Classics from the 80`s in service mpd Aug 29 23:16:51 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:51.576Z level=INFO msg="emitting player state changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 23:16:51 dell volumio5-onboarding[1597]: time=2026-08-29T20:16:51.576Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.185:54242 @ 0xc000393560" id="mnt/NAS/Media/Новая папка/Golden Ballads/94Sonique - It Feel So Good.MP3" title="It Feel So Good" Aug 29 23:16:51 dell volumio[1070]: info: sendMpdCommand stop took 29 milliseconds Aug 29 23:16:51 dell volumio[1070]: info: Aug 29 23:16:51 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:51 dell volumio[1070]: info: ControllerMpd::getState Aug 29 23:16:51 dell volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 29 23:16:51 dell volumio[1070]: info: Aug 29 23:16:51 dell volumio[1070]: ---------------------------- MPD announces state update: player Aug 29 23:16:51 dell volumio[1070]: info: ALBUMART /albumart?cacheid=660&web=Golden%20Earring/Rock%20Classics%20From%20The%2080's%20(Disc%201)/extralarge&path=%2Fmnt%2FNAS%2FMedia%2F%D0%9D%D0%BE%D0%B2%D0%B0%D1%8F%20%D0%BF%D0%B0%D0%BF%D0%BA%D0%B0%2FRock%20Classics%20from%20the%2080%60s%2FCD1%2FVarious%20-%20Rock%20Classics%20From%20The%2080's%20(Disc%201).cue&metadata=false Aug 29 23:16:51 dell volumio[1070]: info: URI /mnt/NAS/Media/Новая папка/Rock Classics from the 80`s/CD1/Various - Rock Classics From The 80's (Disc 1).cue Aug 29 23:16:51 dell volumio[1070]: info: ALBUMART /albumart?cacheid=660&web=Pat%20Benatar/Rock%20Classics%20From%20The%2080's%20(Disc%201)/extralarge&path=%2Fmnt%2FNAS%2FMedia%2F%D0%9D%D0%BE%D0%B2%D0%B0%D1%8F%20%D0%BF%D0%B0%D0%BF%D0%BA%D0%B0%2FRock%20Classics%20from%20the%2080%60s%2FCD1%2FVarious%20-%20Rock%20Classics%20From%20The%2080's%20(Disc%201).cue&metadata=false Aug 29 23:16:51 dell volumio[1070]: info: URI /mnt/NAS/Media/Новая папка/Rock Classics from the 80`s/CD1/Various - Rock Classics From The 80's (Disc 1).cue Aug 29 23:16:51 dell volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 23:16:51 dell volumio[1070]: Error: Unable to resolve or reject the same promise twice Aug 29 23:16:51 dell volumio[1070]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 29 23:16:51 dell volumio[1070]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 29 23:16:51 dell volumio[1070]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 29 23:16:51 dell volumio[1070]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 29 23:16:51 dell volumio[1070]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 29 23:16:51 dell volumio[1070]: at Socket.emit (node:events:514:28) Aug 29 23:16:51 dell volumio[1070]: at addChunk (node:internal/streams/readable:343:12) Aug 29 23:16:51 dell volumio[1070]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 29 23:16:51 dell volumio[1070]: at Readable.push (node:internal/streams/readable:253:10) Aug 29 23:16:51 dell volumio[1070]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 29 23:16:51 dell volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 23:16:51 dell sudo[11576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 23:15' Aug 29 23:16:51 dell sudo[11576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"