-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Tue 2026-08-25 23:22:51 EDT. -- Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.895-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.103:49412 Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.938-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.103:49412 @ 0x2400e70" latency=468.754493ms timeout=20s Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.939-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.938-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.103:49412 @ 0x2400e70" latency=471.336891ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 23:21:27 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:27 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.948-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" name=Volumio Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.952-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" language=en Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.957-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" timezone=America/New_York Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.959-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" available=true connected=false macAddress= ip4Address= ip6Address= Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.962-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" available=true connected=true macAddress=b8:27:eb:60:b2:3c ip4Address=192.168.0.104/24 ip6Address= ssid=TP-Link_82ED_5G Aug 25 23:21:27 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:27.963-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" setupComplete=true Aug 25 23:21:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 23:21:28 volumio volumio[976]: amixer -c 2 info | grep "BossDAC" Aug 25 23:21:28 volumio volumio[976]: Card hw:2 'BossDAC'/'BossDAC' Aug 25 23:21:28 volumio volumio[976]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 25 23:21:28 volumio volumio[976]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 25 23:21:28 volumio volumio[976]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 25 23:21:28 volumio volumio[976]: amixer -c 2 info | grep "BossDAC" Aug 25 23:21:28 volumio volumio[976]: Card hw:2 'BossDAC'/'BossDAC' Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.184-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" selectedOutputId=2 Aug 25 23:21:28 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:28 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:28 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.212-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" currentVersion=3.912 latestVersion=3.912 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.212-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.103:49412 @ 0x2400e70" status=UPDATE_STATUS_NONE progress=0 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.213-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" userId=R8z1IOo4yOMryGwWWBfPaqtJvFw2 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.214-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" providers=9 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.214-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" plugins=54 Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:28 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.222-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:28 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:28.223-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:28 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:28 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:28 volumio volumio[976]: verbose: New Socket.io Connection to 192.168.0.104:3000 from 192.168.0.103 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 23:21:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 23:21:34 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:34.830-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.103:49412 @ 0x2400e70" latency=466.223483ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 25 23:21:34 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:34.864-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.092-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://securetoken.googleapis.com duration=225.65298ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.117-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=249.800096ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.117-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=http://pushupdates.volumio.org duration=253.110451ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.159-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://www.googleapis.com duration=295.106473ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.194-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=329.319236ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.195-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=329.808141ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.267-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://functions.volumio.cloud duration=403.078862ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.274-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://functions.volumio.cloud duration=410.620506ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.278-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://google.com duration=414.059298ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.316-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=448.901175ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.340-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=https://database.volumio.cloud duration=474.411828ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.352-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=http://plugins.volumio.org duration=487.221999ms Aug 25 23:21:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:35.751-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412 @ 0x2400e70" latency=465.924425ms timeout=10s endpoint=http://cddb.volumio.org duration=884.55593ms Aug 25 23:21:36 volumio sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:21:36 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:21:36 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Aug 25 23:21:36 volumio sudo[4856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:21:36 volumio sudo[4856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:21:36 volumio sudo[4856]: pam_unix(sudo:session): session closed for user root Aug 25 23:21:36 volumio volumio[976]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; moto g 5G - 2024 Build/V1UFNS35H.193-20-14; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 25 23:21:36 volumio sudo[4862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:21:36 volumio sudo[4862]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:21:36 volumio sudo[4862]: pam_unix(sudo:session): session closed for user root Aug 25 23:21:36 volumio sudo[4864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:21:36 volumio sudo[4864]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:21:37 volumio sudo[4864]: pam_unix(sudo:session): session closed for user root Aug 25 23:21:37 volumio volumio[976]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; moto g 5G - 2024 Build/V1UFNS35H.193-20-14; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:37 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 23:21:37 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:37 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:37 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:37 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:37 volumio volumio[976]: info: Listing playlists Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 23:21:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:21:38 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:38.643-04:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Aug 25 23:21:38 volumio dbus-daemon[533]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.18" (uid=0 pid=1487 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=652 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:21:38 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:38 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:38 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:38 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 23:21:39 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:21:39 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:39 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:39 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:39 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:39 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:39 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:39 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: info: Retrieving Cloud Streaming UI Aug 25 23:21:43 volumio volumio[976]: info: Getting Tidal Cloud Configuration Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: info: Getting Qobuz Cloud Configuration Aug 25 23:21:43 volumio volumio[976]: info: Asking plugin for UI Config Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: info: Getting Spotify Cloud Configuration Aug 25 23:21:43 volumio volumio[976]: info: Asking plugin for UI Config Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: info: Saving Spotify Acccount Aug 25 23:21:43 volumio volumio[976]: info: Got Tidal Cloud Configuration Aug 25 23:21:43 volumio volumio[976]: info: Got it Aug 25 23:21:43 volumio volumio[976]: info: Got it Aug 25 23:21:43 volumio volumio[976]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:21:43 volumio volumio[976]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:21:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand rescan Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: info: Aug 25 23:21:44 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand rescan took 6 milliseconds Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 7 milliseconds Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:44 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: No code Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.796-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.797-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.799-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.800-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.802-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.803-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio[976]: info: ------------------------------ 110ms Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: info: Aug 25 23:21:44 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: info: Aug 25 23:21:44 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 5 milliseconds Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 5 milliseconds Aug 25 23:21:44 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:44 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:44 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: No code Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:44 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio[976]: info: No code Aug 25 23:21:44 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.936-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.937-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.939-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.940-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.941-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.942-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.944-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.946-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.948-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.949-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.951-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:44 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:44.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:44 volumio volumio[976]: info: ------------------------------ 152ms Aug 25 23:21:44 volumio volumio[976]: info: ------------------------------ 150ms Aug 25 23:21:45 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 23:21:45 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand update Aug 25 23:21:45 volumio volumio[976]: info: sendMpdCommand update took 3 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 95 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 94 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 93 milliseconds Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.641-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.642-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.645-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.646-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.648-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.649-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.650-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.651-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.652-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.657-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.658-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 233ms Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 233ms Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 285 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 285 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 284 milliseconds Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.796-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.796-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.798-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.798-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.800-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.801-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.802-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.802-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.804-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.805-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.806-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.807-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 377ms Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 378ms Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: info: Aug 25 23:21:46 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 439 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 438 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:46 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio[976]: info: No code Aug 25 23:21:46 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:46 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.952-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.954-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.955-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.956-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.957-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.958-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.959-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.961-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.962-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.963-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:46 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:46.963-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 530ms Aug 25 23:21:46 volumio volumio[976]: info: ------------------------------ 530ms Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 94 milliseconds Aug 25 23:21:46 volumio volumio[976]: info: sendMpdCommand status took 91 milliseconds Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:46 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:46 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:46 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.029-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.029-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.031-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.031-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.033-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.033-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 158ms Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 13 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 11 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 11 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 11 milliseconds Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.255-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.257-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.258-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.260-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.261-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.263-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.264-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.267-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.268-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.268-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.269-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 197ms Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 195ms Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand rescan Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand rescan took 5 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 9 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 8 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 8 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.484-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.486-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.486-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.489-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.490-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.493-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.494-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.495-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.497-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.498-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.500-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.501-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.504-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.504-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.504-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.506-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 226ms Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 225ms Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 223ms Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:21:47 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: Aug 25 23:21:47 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 74 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 72 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 73 milliseconds Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.818-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.818-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.820-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.821-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.822-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.824-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.825-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.825-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.827-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.828-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.829-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.830-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 180ms Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 182ms Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 233 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 233 milliseconds Aug 25 23:21:47 volumio volumio[976]: info: sendMpdCommand status took 232 milliseconds Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: 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 25 23:21:47 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio[976]: info: No code Aug 25 23:21:47 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:47 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:47 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.972-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.973-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.974-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.975-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.976-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.977-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.979-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.979-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.981-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.984-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:47 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:47.984-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 330ms Aug 25 23:21:47 volumio volumio[976]: info: ------------------------------ 332ms Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 375 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 375 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 374 milliseconds Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: 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 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: 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 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.110-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.111-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.112-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.113-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.114-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.115-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.116-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.117-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.118-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.119-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.122-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.123-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 468ms Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 468ms Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand update Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand update took 4 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 7 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 5 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.337-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.338-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.338-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.338-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.340-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.341-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.343-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.345-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.349-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.350-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.352-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.352-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.354-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.354-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.356-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.356-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 200ms Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 198ms Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 197ms Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: Aug 25 23:21:48 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 5 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:21:48 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:21:48 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: 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 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: 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 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: 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 25 23:21:48 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio[976]: info: No code Aug 25 23:21:48 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:21:48 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:48 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.550-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.551-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.551-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.552-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.553-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.554-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.555-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.560-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.561-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.564-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.565-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.566-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.567-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.569-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:21:48 volumio volumio5-onboarding[1487]: time=2026-08-25T23:21:48.571-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 205ms Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 193ms Aug 25 23:21:48 volumio volumio[976]: info: ------------------------------ 192ms Aug 25 23:21:51 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:21:55 volumio volumio[976]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 23:21:55 volumio volumio[976]: info: Received Get System Version Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 23:21:55 volumio volumio[976]: info: Received Get System Info Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:21:55 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:21:55 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:21:55 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:21:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:03 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:07 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:11 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:12 volumio volumio[976]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Aug 25 23:22:12 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Aug 25 23:22:12 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Aug 25 23:22:12 volumio volumio[976]: info: Rebuild Album cache Aug 25 23:22:15 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:16 volumio volumio[976]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Aug 25 23:22:16 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Aug 25 23:22:16 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Aug 25 23:22:16 volumio volumio[976]: info: Rebuild Album cache Aug 25 23:22:19 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:20 volumio volumio[976]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Aug 25 23:22:20 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.378-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=477.808485ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.411-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.429-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=17.017128ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.429-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=17.959416ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.433-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=20.006023ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.449-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://securetoken.googleapis.com duration=36.573412ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.462-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=49.779667ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.475-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://functions.volumio.cloud duration=62.455612ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.478-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://functions.volumio.cloud duration=64.952582ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.480-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://google.com duration=68.223195ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.483-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://database.volumio.cloud duration=70.22048ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.510-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=http://pushupdates.volumio.org duration=97.758819ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.528-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=https://www.googleapis.com duration=116.153025ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.684-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=http://plugins.volumio.org duration=271.634921ms Aug 25 23:22:25 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:25.699-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" latency=466.726961ms timeout=10s endpoint=http://cddb.volumio.org duration=286.759607ms Aug 25 23:22:25 volumio sudo[5029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:22:25 volumio sudo[5029]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:22:25 volumio sudo[5029]: pam_unix(sudo:session): session closed for user root Aug 25 23:22:25 volumio sudo[5031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:22:25 volumio sudo[5031]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:22:25 volumio sudo[5031]: pam_unix(sudo:session): session closed for user root Aug 25 23:22:25 volumio volumio[976]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; moto g 5G - 2024 Build/V1UFNS35H.193-20-14; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 25 23:22:26 volumio sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:22:26 volumio sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:22:26 volumio sudo[5035]: pam_unix(sudo:session): session closed for user root Aug 25 23:22:26 volumio sudo[5037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:22:26 volumio sudo[5037]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:22:26 volumio sudo[5037]: pam_unix(sudo:session): session closed for user root Aug 25 23:22:26 volumio volumio[976]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; moto g 5G - 2024 Build/V1UFNS35H.193-20-14; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:26 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 23:22:26 volumio volumio[976]: info: Received Get System Info Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:22:26 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:26 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:26 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:26 volumio volumio[976]: info: Listing playlists Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:22:26 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:22:28 volumio volumio[976]: info: Received Get System Info Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:22:28 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:28 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:22:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:22:29 volumio volumio[976]: info: Received Get System Info Aug 25 23:22:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:22:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:22:29 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:22:29 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:29 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: info: Retrieving Cloud Streaming UI Aug 25 23:22:33 volumio volumio[976]: info: Getting Tidal Cloud Configuration Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: info: Getting Qobuz Cloud Configuration Aug 25 23:22:33 volumio volumio[976]: info: Asking plugin for UI Config Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: info: Getting Spotify Cloud Configuration Aug 25 23:22:33 volumio volumio[976]: info: Asking plugin for UI Config Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: info: Saving Spotify Acccount Aug 25 23:22:33 volumio volumio[976]: info: Got Tidal Cloud Configuration Aug 25 23:22:33 volumio volumio[976]: info: Got it Aug 25 23:22:33 volumio volumio[976]: info: Got it Aug 25 23:22:33 volumio volumio[976]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 23:22:33 volumio volumio[976]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:33 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand rescan Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand rescan took 3 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 8 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 7 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 6 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.309-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.311-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.312-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.312-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.315-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.317-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.323-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.323-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.326-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.327-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.329-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.330-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.332-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.333-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.335-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.335-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.339-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.340-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 331ms Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 330ms Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 329ms Aug 25 23:22:35 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: MPD Database updated - AlbumList cache refreshed Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: database Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 69 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 68 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 68 milliseconds Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.578-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.579-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.582-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.584-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.585-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.587-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.589-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.591-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.599-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.599-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.601-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.603-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 203ms Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 203ms Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 243 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 243 milliseconds Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.743-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.745-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.746-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.748-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.749-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.751-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.752-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.753-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.754-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.756-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 347ms Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 348ms Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 385 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 385 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 384 milliseconds Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: 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 25 23:22:35 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio[976]: info: No code Aug 25 23:22:35 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:35 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.877-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.879-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.879-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.880-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.881-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.882-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.884-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.885-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.886-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.890-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:35 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:35.890-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 479ms Aug 25 23:22:35 volumio volumio[976]: info: ------------------------------ 480ms Aug 25 23:22:35 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand update Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand update took 9 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: Aug 25 23:22:35 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:35 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 20 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 18 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 18 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:22:35 volumio volumio[976]: info: sendMpdCommand status took 16 milliseconds Aug 25 23:22:35 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatetrue Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.200-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.202-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.204-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.204-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.205-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.206-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.207-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.207-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.209-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.211-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.212-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.213-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.213-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.214-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.217-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.217-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.218-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.219-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 293ms Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 291ms Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 290ms Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: info: Aug 25 23:22:36 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: info: Aug 25 23:22:36 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: info: Aug 25 23:22:36 volumio volumio[976]: ---------------------------- MPD announces state update: update Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::getState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 16 milliseconds Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 15 milliseconds Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 5 milliseconds Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 4 milliseconds Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds Aug 25 23:22:36 volumio volumio[976]: info: sendMpdCommand status took 2 milliseconds Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: Command Router : Notfying DB Updatefalse Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::Close All Modals sent Aug 25 23:22:36 volumio volumio[976]: verbose: ControllerMpd::parseState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: 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 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: 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 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: ControllerMpd::pushState Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::servicePushState Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: 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 25 23:22:36 volumio volumio[976]: verbose: CURRENT POSITION 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio[976]: info: No code Aug 25 23:22:36 volumio volumio[976]: info: CoreStateMachine::pushState Aug 25 23:22:36 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::volumioPushState Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.415-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.415-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.417-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.418-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.419-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.419-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.422-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.423-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.423-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.425-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.426-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.427-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.428-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.429-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.430-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.432-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" state=STATUS_STOPPED positionMs=0 volume=75 Aug 25 23:22:36 volumio volumio5-onboarding[1487]: time=2026-08-25T23:22:36.433-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.103:49412,00:00:00:00:00:00%01 @ 0x2400e70" id="mnt/USB/coco/Bill Evans/Bill Evans Trio - Portrait in Jazz/01 Come Rain or Come Shine.flac" title="Come Rain or Come Shine" Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 219ms Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 208ms Aug 25 23:22:36 volumio volumio[976]: info: ------------------------------ 208ms Aug 25 23:22:36 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 23:22:37 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:41 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:22:44 volumio volumio[976]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 23:22:44 volumio volumio[976]: info: Received Get System Version Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 23:22:44 volumio volumio[976]: info: Received Get System Info Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:22:44 volumio volumio[976]: info: Discovery: Getting this device information Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::volumioGetState Aug 25 23:22:44 volumio volumio[976]: info: CorePlayQueue::getTrack 0 Aug 25 23:22:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:22:45 volumio volumio[976]: info: CoreCommandRouter::volumioGetQueue Aug 25 23:22:45 volumio volumio[976]: info: CoreStateMachine::getQueue Aug 25 23:22:45 volumio volumio[976]: info: CorePlayQueue::getQueue Aug 25 23:22:50 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 23:22:50 volumio volumio[976]: info: CURURI: artists:// Aug 25 23:22:50 volumio volumio[976]: info: Preload queue cleared Aug 25 23:22:51 volumio volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:22:51 volumio volumio[976]: Error: connect ECONNREFUSED 158.69.209.125:443 Aug 25 23:22:51 volumio volumio[976]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 25 23:22:51 volumio volumio[976]: errno: -111, Aug 25 23:22:51 volumio volumio[976]: code: 'ECONNREFUSED', Aug 25 23:22:51 volumio volumio[976]: syscall: 'connect', Aug 25 23:22:51 volumio volumio[976]: address: '158.69.209.125', Aug 25 23:22:51 volumio volumio[976]: port: 443 Aug 25 23:22:51 volumio volumio[976]: } Aug 25 23:22:51 volumio volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:22:51 volumio sudo[5156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-25 23:21 Aug 25 23:22:51 volumio sudo[5156]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"