Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.090Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:00 volumio volumiossh-tunnel[21597]: Connection to eu6.myvolumio.org closed by remote host. Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 2) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23031 Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.159Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=69.580653ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.176Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=http://pushupdates.volumio.org duration=84.341568ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.176Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=86.181656ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.176Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=84.000529ms Aug 29 13:18:00 volumio volumiossh-tunnel[23031]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.247Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://www.googleapis.com duration=156.368136ms Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 3) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23035 Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.252Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://securetoken.googleapis.com duration=160.90466ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.270Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=178.598466ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.271Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://functions.volumio.cloud duration=179.715228ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.275Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://database.volumio.cloud duration=183.283686ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.276Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://functions.volumio.cloud duration=184.058419ms Aug 29 13:18:00 volumio volumiossh-tunnel[23035]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 4) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23036 Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.383Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=http://cddb.volumio.org duration=291.356196ms Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:00 volumio volumiossh-tunnel[23036]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 5) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23040 Aug 29 13:18:00 volumio sudo[23041]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 13:18:00 volumio sudo[23041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.530Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=http://plugins.volumio.org duration=438.279465ms Aug 29 13:18:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:00.531Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:60088 @ 0x207e030" latency=855.96065ms timeout=10s endpoint=https://google.com duration=440.970796ms Aug 29 13:18:00 volumio sudo[23043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 13:18:00 volumio sudo[23041]: pam_unix(sudo:session): session closed for user root Aug 29 13:18:00 volumio sudo[23043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 13:18:00 volumio sudo[23043]: pam_unix(sudo:session): session closed for user root Aug 29 13:18:00 volumio volumiossh-tunnel[23040]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 6) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23050 Aug 29 13:18:00 volumio volumiossh-tunnel[23050]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio autossh[21593]: starting ssh (count 7) Aug 29 13:18:00 volumio autossh[21593]: ssh child pid is 23051 Aug 29 13:18:00 volumio volumio[3064]: verbose: New Socket.io Connection to 192.168.3.219 from 192.168.3.214 UA: Mozilla/5.0 (Linux; Android 15; Armor X12 Pro Build/AP3A.240905.015.A2; 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: 11 Aug 29 13:18:00 volumio volumiossh-tunnel[23051]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:00 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:00 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:00 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:01 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:01 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:01 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:01 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:01 volumio volumio[3064]: verbose: New Socket.io Connection to 192.168.3.219:3000 from 192.168.3.214 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 13:18:01 volumio sudo[23065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 13:18:01 volumio sudo[23065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 13:18:01 volumio sudo[23065]: pam_unix(sudo:session): session closed for user root Aug 29 13:18:01 volumio sudo[23067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 13:18:01 volumio sudo[23067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 13:18:01 volumio sudo[23067]: pam_unix(sudo:session): session closed for user root Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:01 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:01 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:01 volumio volumio[3064]: verbose: New Socket.io Connection to 192.168.3.219 from 192.168.3.214 UA: Mozilla/5.0 (Linux; Android 15; Armor X12 Pro Build/AP3A.240905.015.A2; 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: 12 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 13:18:02 volumio volumio[3064]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 13:18:02 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:02 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: Listing playlists Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:02 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:02 volumio autossh[21593]: starting ssh (count 8) Aug 29 13:18:02 volumio autossh[21593]: ssh child pid is 23093 Aug 29 13:18:03 volumio volumiossh-tunnel[23093]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:03 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 13:18:03 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:03 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:03 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:03 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:04 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 13:18:04 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:04 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:04 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:04 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:04 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:04 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:04 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:04 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: Retrieving Cloud Streaming UI Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: Getting Tidal Cloud Configuration Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: Getting Qobuz Cloud Configuration Aug 29 13:18:05 volumio volumio[3064]: info: Asking plugin for UI Config Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: Getting Spotify Cloud Configuration Aug 29 13:18:05 volumio volumio[3064]: info: Asking plugin for UI Config Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: Saving Spotify Acccount Aug 29 13:18:05 volumio volumio[3064]: info: Got it Aug 29 13:18:05 volumio volumio[3064]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: Got Tidal Cloud Configuration Aug 29 13:18:05 volumio volumio[3064]: info: Got it Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 13:18:05 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:05 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:05 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:05 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:06 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:06 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:07 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:07 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:07 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:07 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:07 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:07 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:07 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:07 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:08 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:08 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:09 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:09 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:10 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:10 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:10 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:10 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:10 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:10 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:10 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:10 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:11 volumio autossh[21593]: starting ssh (count 9) Aug 29 13:18:11 volumio autossh[21593]: ssh child pid is 23237 Aug 29 13:18:11 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:11 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:11 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:11 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:11 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:11 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:11 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:11 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetQueue Aug 29 13:18:13 volumio volumio[3064]: info: CoreStateMachine::getQueue Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getQueue Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:13 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:13 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 13:18:14 volumio volumio[3064]: info: CURURI: music-library Aug 29 13:18:14 volumio volumio[3064]: info: Preload queue cleared Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:14 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:14 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:15 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:15 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:15 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:15 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:15 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:15 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:15 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:15 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:16 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:16 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:17 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:17 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:18 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:18 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:18 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:18 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:18 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:18 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:18 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:18 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:19 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:19 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:20 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:20 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:21 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:21 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:21 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:21 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 13:18:21 volumio volumio[3064]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 13:18:21 volumio volumio[3064]: info: Received Get System Version Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 13:18:21 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:21 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:21 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:21 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:22 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:22 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.012Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.3.214:60088 error="websocket: close 1001 (going away)" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.013Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.3.214:60088 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.013Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.3.214:60088 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.029Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.214:36184 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.074Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.088Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.214:36184 @ 0x1cc7b30" latency=851.051019ms timeout=20s Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.088Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" Aug 29 13:18:23 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:23 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.095Z level=INFO msg="emitting device name changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" name=Volumio Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.096Z level=INFO msg="emitting device language changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" language=pl Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.098Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" timezone=Europe/Warsaw Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.102Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" available=true connected=false macAddress= ip4Address= ip6Address= Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.119Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" available=true connected=true macAddress=b8:27:eb:bb:61:40 ip4Address=192.168.3.219/24 ip6Address= ssid=HUAWEI-1CT6G6 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.120Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" setupComplete=true Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.135Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=59.980048ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.148Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=72.65605ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.149Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=http://pushupdates.volumio.org duration=73.123128ms Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.156Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=81.069757ms Aug 29 13:18:23 volumio volumio[3064]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 29 13:18:23 volumio volumio[3064]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.224Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://www.googleapis.com duration=147.712855ms Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.249Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.3.214:36184 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.249Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.3.214:36184 Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 2 info | grep "vc4-hdmi" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.265Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.214:36204 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.265Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=190.030812ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.265Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://securetoken.googleapis.com duration=190.465598ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.272Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://functions.volumio.cloud duration=197.077555ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.274Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://functions.volumio.cloud duration=197.771974ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.285Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://database.volumio.cloud duration=209.143302ms Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 3 info | grep "Raspberry Pi DAC+" Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 3 info | grep "RPi DAC+" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.333Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.214:36204 @ 0x1d0a240" latency=851.661304ms timeout=20s Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.333Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:3 'DAC'/'RPi DAC+' Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.362Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" selectedOutputId=3 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.362Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_AUDIO_OUTPUTS_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:23 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.371Z level=INFO msg="emitting device name changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" name=Volumio Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.372Z level=INFO msg="emitting device language changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" language=pl Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.381Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" timezone=Europe/Warsaw Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.383Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" available=true connected=false macAddress= ip4Address= ip6Address= Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.388Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" available=true connected=true macAddress=b8:27:eb:bb:61:40 ip4Address=192.168.3.219/24 ip6Address= ssid=HUAWEI-1CT6G6 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.389Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" setupComplete=true Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 13:18:23 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:23 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:23 volumio volumio[3064]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 29 13:18:23 volumio volumio[3064]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.503Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=https://google.com duration=427.001142ms Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=INFO msg="emitting software info changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" currentVersion=4.119 latestVersion=4.119 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_SOFTWARE_INFO_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=INFO msg="emitting software update progress event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" status=UPDATE_STATUS_NONE progress=0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_SOFTWARE_UPDATE_PROGRESS error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=INFO msg="emitting user changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" userId=46BzcmiOBff6K5RRpOOuuE5GbH32 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_USER_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.528Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" providers=3 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.529Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_MUSIC_PROVIDERS_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.529Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" plugins=71 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.529Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_PLUGINS_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 3 info | grep "RPi DAC+" Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:3 'DAC'/'RPi DAC+' Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 2 info | grep "vc4-hdmi" Aug 29 13:18:23 volumio volumio[3064]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 29 13:18:23 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:23 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:23 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:23 volumio volumio[3064]: amixer -c 3 info | grep "Raspberry Pi DAC+" Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.626Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" selectedOutputId=3 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 13:18:23 volumio volumio[3064]: info: CURURI: music-library/USB Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.631Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.631Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.631Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36184 @ 0x1cc7b30" id= title= Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.631Z level=ERROR msg="failed to send event" component=server dst=" @ 0x1cc7b30" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.631Z level=ERROR msg="failed to send response" component=server dst=" @ 0x1cc7b30" id=535065030 status=STATUS_OK error="no addresses to write to" Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.646Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=http://cddb.volumio.org duration=570.779458ms Aug 29 13:18:23 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:23 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.665Z level=INFO msg="emitting software info changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" currentVersion=4.119 latestVersion=4.119 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.665Z level=INFO msg="emitting software update progress event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" status=UPDATE_STATUS_NONE progress=0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.665Z level=INFO msg="emitting user changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" userId=46BzcmiOBff6K5RRpOOuuE5GbH32 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.666Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" providers=3 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.666Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" plugins=71 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.670Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 13:18:23 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:23.671Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id= title= Aug 29 13:18:23 volumio volumio[3064]: info: Preload queue cleared Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:23 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:23 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:24.030Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:36184 @ 0x1cc7b30" latency=846.844086ms timeout=10s endpoint=http://plugins.volumio.org duration=954.552285ms Aug 29 13:18:24 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:24.030Z level=ERROR msg="failed to send response" component=server dst=" @ 0x1cc7b30" id=703042212 status=STATUS_OK error="no addresses to write to" Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:24 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:24 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:25 volumio volumiossh-tunnel[23237]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:25 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:25 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:25 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:25 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:25 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:25 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 13:18:25 volumio volumio[3064]: info: CURURI: music-library/USB/Muzyka Aug 29 13:18:25 volumio volumio[3064]: info: Preload queue cleared Aug 29 13:18:25 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:25 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:25 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:25 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:26 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:26 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:27 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:27 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:27 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:27 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:27 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:27 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:27 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:27 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: Preload queue cleared Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::ClearQueue Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::stop Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::clearPlayQueue Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::saveQueue Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioPushQueue Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::addQueueItems Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::addQueueItems Aug 29 13:18:28 volumio volumio[3064]: info: Preload queue cleared Aug 29 13:18:28 volumio volumio[3064]: info: Adding Item to queue: music-library/USB/Muzyka/2 Unlimited Aug 29 13:18:28 volumio volumio[3064]: info: Exploding uri music-library/USB/Muzyka/2 Unlimited in service mpd Aug 29 13:18:28 volumio volumio[3064]: info: ALBUMART /albumart?cacheid=765&web=2%20Unlimited/Hits%20Unlimited/extralarge&path=%2Fmnt%2FUSB%2FMuzyka%2F2%20Unlimited%2F2%20Unlimited%20-%20No%20One.flac&metadata=false Aug 29 13:18:28 volumio volumio[3064]: info: URI /mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac Aug 29 13:18:28 volumio volumio[3064]: info: ALBUMART /albumart?cacheid=765&web=2%20Unlimited//extralarge&path=%2Fmnt%2FUSB%2FMuzyka%2F2%20Unlimited%2F2%20Unlimited-No%20limit.wav&metadata=false Aug 29 13:18:28 volumio volumio[3064]: info: URI /mnt/USB/Muzyka/2 Unlimited/2 Unlimited-No limit.wav Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioPushQueue Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::saveQueue Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::updateTrackBlock Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrackBlock Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioPlay Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::play index 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::stop Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::play index undefined Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreStateMachine::startPlaybackTimer Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::clearAddPlayTracks USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 13:18:28 volumio volumio[3064]: info: sendMpdCommand stop took 4 milliseconds Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces system playlist update Aug 29 13:18:28 volumio volumio[3064]: info: Ignoring MPD Status Update Aug 29 13:18:28 volumio volumio[3064]: info: sendMpdCommand clear took 10 milliseconds Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand add "USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces system playlist update Aug 29 13:18:28 volumio volumio[3064]: info: Ignoring MPD Status Update Aug 29 13:18:28 volumio volumio[3064]: error: updateQueue error: null Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces system playlist update Aug 29 13:18:28 volumio volumio[3064]: info: Ignoring MPD Status Update Aug 29 13:18:28 volumio volumio[3064]: info: ------------------------------ 7ms Aug 29 13:18:28 volumio volumio[3064]: info: sendMpdCommand add "USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" took 5 milliseconds Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand play Aug 29 13:18:28 volumio volumio[3064]: info: ------------------------------ 9ms Aug 29 13:18:28 volumio volumio[3064]: info: ------------------------------ 7ms Aug 29 13:18:28 volumio volumio[3064]: info: sendMpdCommand play took 5 milliseconds Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces system playlist update Aug 29 13:18:28 volumio volumio[3064]: info: Ignoring MPD Status Update Aug 29 13:18:28 volumio volumio[3064]: info: ------------------------------ 2ms Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:28 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:28 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:28 volumio volumio[3064]: info: Aug 29 13:18:28 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:28 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:28 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:29 volumio volumio[3064]: info: Aug 29 13:18:29 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand status took 338 milliseconds Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:29 volumio volumio[3064]: info: Aug 29 13:18:29 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand status took 342 milliseconds Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand status took 4 milliseconds Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:29 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState stateService play Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus stop Aug 29 13:18:29 volumio volumio[3064]: info: ------------------------------ 352ms Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand status took 10 milliseconds Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:29 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState stateService play Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus play Aug 29 13:18:29 volumio volumio[3064]: info: Received an update from plugin. extracting info from payload Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"919 Kbps","isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:29 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState stateService play Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus play Aug 29 13:18:29 volumio volumio[3064]: info: Received an update from plugin. extracting info from payload Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.228Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=45 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.231Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=45 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.231Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.232Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=1100 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.233Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=1100 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.235Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.237Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.237Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio[3064]: info: ------------------------------ 401ms Aug 29 13:18:29 volumio volumio[3064]: info: ------------------------------ 66ms Aug 29 13:18:29 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 29 13:18:29 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:29 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"919 Kbps","isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:29 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState stateService play Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus play Aug 29 13:18:29 volumio volumio[3064]: info: Received an update from plugin. extracting info from payload Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:29 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.264Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=1100 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.266Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PLAYING positionMs=1100 volume=100 Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.266Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:29.267Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:29 volumio volumio[3064]: info: ------------------------------ 81ms Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:29 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:29 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:30 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:30 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:31 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:31 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:31 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:31 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:31 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:31 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:31 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:31 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:32 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:32 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:32 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:32 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:33 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:33 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:33 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:33 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:33 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:33 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:33 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:33 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:34 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:34 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:35 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:35 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:35 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:35 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:35 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:35 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:35 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:35 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:36 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:36 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioPause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::pause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::stPlaybackTimer Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::servicePause Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::servicePause Aug 29 13:18:37 volumio volumio[3064]: info: ControllerMpd::pause Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 13:18:37 volumio volumio[3064]: info: Aug 29 13:18:37 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:37 volumio volumio[3064]: info: sendMpdCommand pause took 2 milliseconds Aug 29 13:18:37 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:37 volumio volumio[3064]: info: Aug 29 13:18:37 volumio volumio[3064]: ---------------------------- MPD announces state update: player Aug 29 13:18:37 volumio volumio[3064]: info: ControllerMpd::getState Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand status Aug 29 13:18:37 volumio volumio[3064]: info: sendMpdCommand status took 3 milliseconds Aug 29 13:18:37 volumio volumio[3064]: info: sendMpdCommand status took 2 milliseconds Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::parseState Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 13:18:37 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 13:18:37 volumio volumio[3064]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:37 volumio volumio[3064]: verbose: ControllerMpd::parseTrackInfo Aug 29 13:18:37 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11210,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"790 Kbps","isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:37 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::syncState stateService pause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus pause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:37 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:37 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::stPlaybackTimer Aug 29 13:18:37 volumio volumio[3064]: info: ControllerMpd::pushState Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::servicePushState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11210,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"790 Kbps","isStreaming":false,"title":"No One","artist":"2 Unlimited","album":"Hits Unlimited","uri":"USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac","trackType":"flac"} Aug 29 13:18:37 volumio volumio[3064]: verbose: CURRENT POSITION 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::syncState stateService pause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::syncState currentStatus pause Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::pushState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioPushState Aug 29 13:18:37 volumio volumio[3064]: info: MRS: Pushing multiroomSync output update for this device Aug 29 13:18:37 volumio volumio[3064]: info: MRS: Pushing multiroomSync output Aug 29 13:18:37 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:37 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:37 volumio volumio[3064]: info: CoreStateMachine::stPlaybackTimer Aug 29 13:18:37 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:37.903Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PAUSED positionMs=9914 volume=100 Aug 29 13:18:37 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:37.904Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:37 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:37.908Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" state=STATUS_PAUSED positionMs=9914 volume=100 Aug 29 13:18:37 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:37.908Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:36204 @ 0x1d0a240" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:37 volumio volumio[3064]: info: ------------------------------ 41ms Aug 29 13:18:37 volumio volumio[3064]: info: ------------------------------ 39ms Aug 29 13:18:38 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:38 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:38 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:38 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:38 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:38.434Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.3.214:36204 error="websocket: close 1001 (going away)" Aug 29 13:18:38 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:38.434Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.3.214:36204 Aug 29 13:18:38 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:38.434Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.3.214:36204 Aug 29 13:18:38 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:38.697Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.214:37042 Aug 29 13:18:38 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:38 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:38 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:38 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:39 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:39 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:39 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:39 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:39 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:39 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:39 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:39 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:40 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:40 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:41 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:41 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:41 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:41 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:41 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:41 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:41 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:41 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:41 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:41 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:42 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:42 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:43 volumio autossh[21593]: starting ssh (count 10) Aug 29 13:18:43 volumio autossh[21593]: ssh child pid is 23793 Aug 29 13:18:43 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:43 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:43 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:43 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:43 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:43 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:43 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:43 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:44 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:44 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:45 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:45 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:46 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:46 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:46 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:46 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:46 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:46 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:46 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:46 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:47 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:47 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:48 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:48 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:49 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:49 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:49 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:49 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:49 volumio volumio[3064]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/2%20Unlimited/Hits%20Unlimited/d1b06fde-beb4-4ff5-aab3-7bf4900c258f.jpg' Aug 29 13:18:49 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:49 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:49 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:49 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:50 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:50 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:50.919Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.3.214:37042 error="websocket: close 1001 (going away)" Aug 29 13:18:50 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:50.919Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.3.214:37042 Aug 29 13:18:50 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:50.919Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.3.214:37042 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:51 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:51 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:52 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:52 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:52 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:52 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:52 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:52 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:52 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:52 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:53 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:53 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:54 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:54 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:55 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:55 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:55 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:55 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:55 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:55 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:55 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:55 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.225Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.214:57388 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.288Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.214:57388 @ 0x1d0bb00" latency=847.601186ms timeout=20s Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.288Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" Aug 29 13:18:56 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:56 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.293Z level=INFO msg="emitting device name changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" name=Volumio Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.295Z level=INFO msg="emitting device language changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" language=pl Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.298Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" timezone=Europe/Warsaw Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.299Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" available=true connected=false macAddress= ip4Address= ip6Address= Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.311Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" available=true connected=true macAddress=b8:27:eb:bb:61:40 ip4Address=192.168.3.219/24 ip6Address= ssid=HUAWEI-1CT6G6 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.311Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" setupComplete=true Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 13:18:56 volumio volumio[3064]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 29 13:18:56 volumio volumio[3064]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 29 13:18:56 volumio volumio[3064]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 29 13:18:56 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 29 13:18:56 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:56 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:56 volumio volumio[3064]: amixer -c 2 info | grep "vc4-hdmi" Aug 29 13:18:56 volumio volumio[3064]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 29 13:18:56 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:56 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:56 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:56 volumio volumio[3064]: amixer -c 3 info | grep "Raspberry Pi DAC+" Aug 29 13:18:56 volumio volumio[3064]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 29 13:18:56 volumio volumio[3064]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 29 13:18:56 volumio volumio[3064]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 29 13:18:56 volumio volumio[3064]: amixer -c 3 info | grep "RPi DAC+" Aug 29 13:18:56 volumio volumio[3064]: Card sysdefault:3 'DAC'/'RPi DAC+' Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.542Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" selectedOutputId=3 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: Received Get System Info Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 13:18:56 volumio volumio[3064]: info: Discovery: Getting this device information Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.579Z level=INFO msg="emitting software info changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" currentVersion=4.119 latestVersion=4.119 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.580Z level=INFO msg="emitting software update progress event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" status=UPDATE_STATUS_NONE progress=0 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.580Z level=INFO msg="emitting user changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" userId=46BzcmiOBff6K5RRpOOuuE5GbH32 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.580Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" providers=3 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.581Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" plugins=71 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" state=STATUS_PAUSED positionMs=10164 volume=100 Aug 29 13:18:56 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:56.587Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.214:57388 @ 0x1d0bb00" id="mnt/USB/Muzyka/2 Unlimited/2 Unlimited - No One.flac" title="No One" Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:56 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:56 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:57 volumio volumiossh-tunnel[23793]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 13:18:57 volumio autossh[21593]: ssh exited with error status 255; restarting ssh Aug 29 13:18:57 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:57 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:57 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:57 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:57 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:57.650Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.3.214:57388 @ 0x1d0bb00" latency=879.393954ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 29 13:18:57 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:57 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:57 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:57 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:57 volumio volumio5-onboarding[1671]: time=2026-08-29T11:18:57.847Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s Aug 29 13:18:58 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:58 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:58 volumio volumio[3064]: info: CoreCommandRouter::volumioGetState Aug 29 13:18:58 volumio volumio[3064]: info: CorePlayQueue::getTrack 0 Aug 29 13:18:58 volumio volumio[3064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 13:18:58 volumio volumio[3064]: Error: getaddrinfo ENOTFOUND lastfm-img.freetls.fastly.net Aug 29 13:18:58 volumio volumio[3064]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Aug 29 13:18:58 volumio volumio[3064]: errno: -3007, Aug 29 13:18:58 volumio volumio[3064]: code: 'ENOTFOUND', Aug 29 13:18:58 volumio volumio[3064]: syscall: 'getaddrinfo', Aug 29 13:18:58 volumio volumio[3064]: hostname: 'lastfm-img.freetls.fastly.net' Aug 29 13:18:58 volumio volumio[3064]: } Aug 29 13:18:58 volumio volumio[3064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 13:19:00 volumio sudo[24114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 13:18' Aug 29 13:19:00 volumio sudo[24114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.752Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=2.904844227s Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.752Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=http://pushupdates.volumio.org duration=2.904723969s Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.752Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=2.904150331s Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.753Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=2.905371146s Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.836Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=https://www.googleapis.com duration=2.988549905s Aug 29 13:19:00 volumio volumio5-onboarding[1671]: time=2026-08-29T11:19:00.846Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.214:57388 @ 0x1d0bb00" latency=845.593112ms timeout=10s endpoint=https://securetoken.googleapis.com duration=2.998354179s PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"