Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.757+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.13:50386 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.781+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.260315ms timeout=20s Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.782+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" Aug 27 19:37:05 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:05 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:05 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.787+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" name=Primo Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.787+03:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.788+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" language=tr Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.793+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" timezone=Europe/Istanbul Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.795+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" available=true connected=true macAddress=02:00:00:1c:28:01 ip4Address=192.168.1.197/24 ip6Address= Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.798+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.798+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" setupComplete=true Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.835+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" selectedOutputId=0,0 Aug 27 19:37:05 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:05 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:05 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.884+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" currentVersion=4.158 latestVersion=4.158 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.885+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" status=UPDATE_STATUS_NONE progress=0 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.885+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" userId= Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.885+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" providers=3 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.886+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" plugins=24 Aug 27 19:37:05 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:05 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.891+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" state=STATUS_STOPPED positionMs=270 volume=100 Aug 27 19:37:05 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:05.892+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:50386 @ 0x1ab2180" id=qobuz://song/53377462 title="Born to Run" Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.022+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=http://pushupdates.volumio.org duration=229.733526ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.142+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=354.103048ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.270+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://securetoken.googleapis.com duration=480.28341ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.270+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://www.googleapis.com duration=481.660123ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.326+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://myvolumio.firebaseio.com duration=537.723468ms Aug 27 19:37:06 primo volumio[3534]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.371+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=583.32849ms Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:06 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::volumioGetQueue Aug 27 19:37:06 primo volumio[3534]: info: CoreStateMachine::getQueue Aug 27 19:37:06 primo volumio[3534]: info: CorePlayQueue::getQueue Aug 27 19:37:06 primo volumio[3534]: info: Listing playlists Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 19:37:06 primo volumio[3534]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 19:37:06 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:06 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:06 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:06 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.470+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://functions.volumio.cloud duration=680.022465ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.471+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://functions.volumio.cloud duration=678.918753ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.479+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=689.001702ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.520+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=http://cddb.volumio.org duration=730.116542ms Aug 27 19:37:06 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.671+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=http://plugins.volumio.org duration=872.603038ms Aug 27 19:37:06 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:06.891+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://database.volumio.cloud duration=1.100577809s Aug 27 19:37:07 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:07.239+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-433.796µs timeout=10s endpoint=https://google.com duration=1.451219888s Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:08 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:08 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:08 primo volumio[3534]: verbose: New Socket.io Connection to 192.168.1.197:3000 from 192.168.1.13 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 19:37:08 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.710+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.132958ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.729+03:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.773+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=43.738557ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.774+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=http://pushupdates.volumio.org duration=43.176388ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.786+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=55.023259ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.790+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=60.079275ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.885+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://www.googleapis.com duration=155.263871ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.896+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=165.302653ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.897+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://securetoken.googleapis.com duration=166.724157ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.900+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://functions.volumio.cloud duration=170.740337ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.901+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://functions.volumio.cloud duration=170.119627ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.990+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=http://cddb.volumio.org duration=259.952122ms Aug 27 19:37:08 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:08.998+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://database.volumio.cloud duration=267.220771ms Aug 27 19:37:09 primo sudo[11552]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 19:37:09 primo sudo[11552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:37:09 primo sudo[11554]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 19:37:09 primo sudo[11554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:37:09 primo sudo[11552]: pam_unix(sudo:session): session closed for user root Aug 27 19:37:09 primo sudo[11554]: pam_unix(sudo:session): session closed for user root Aug 27 19:37:09 primo volumio[3534]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 19:37:09 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:09.126+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=http://plugins.volumio.org duration=395.315179ms Aug 27 19:37:09 primo sudo[11562]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 19:37:09 primo sudo[11561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 19:37:09 primo sudo[11562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:37:09 primo sudo[11561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:37:09 primo sudo[11561]: pam_unix(sudo:session): session closed for user root Aug 27 19:37:09 primo sudo[11562]: pam_unix(sudo:session): session closed for user root Aug 27 19:37:09 primo volumio[3534]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 27 19:37:09 primo volumio[3534]: info: Executing endpoint metavolumio Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 19:37:09 primo volumio[3534]: info: Executing endpoint metavolumio Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 19:37:09 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:09.436+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50386 @ 0x1ab2180" latency=-3.252895ms timeout=10s endpoint=https://google.com duration=706.051755ms Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:09 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::volumioGetQueue Aug 27 19:37:09 primo volumio[3534]: info: CoreStateMachine::getQueue Aug 27 19:37:09 primo volumio[3534]: info: CorePlayQueue::getQueue Aug 27 19:37:09 primo volumio[3534]: info: Listing playlists Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 19:37:09 primo volumio[3534]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 19:37:09 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:09 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:09 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:09 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 19:37:09 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:37:11 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:11 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:11 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.247+03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.13:50386 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.247+03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.13:50386 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.254+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.13:50501 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.273+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:50501 @ 0x1ab2660" latency=-2.852525ms timeout=20s Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.273+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.274+03:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s Aug 27 19:37:11 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:11 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:11 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.281+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" name=Primo Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.282+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" language=tr Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.285+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" timezone=Europe/Istanbul Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.287+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" available=true connected=true macAddress=02:00:00:1c:28:01 ip4Address=192.168.1.197/24 ip6Address= Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.289+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.290+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" setupComplete=true Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.317+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=42.29701ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.319+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=http://pushupdates.volumio.org duration=43.721223ms Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.326+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=52.456543ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.330+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" selectedOutputId=0,0 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.332+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=57.014557ms Aug 27 19:37:11 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:11 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:11 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.381+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" currentVersion=4.158 latestVersion=4.158 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.381+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" status=UPDATE_STATUS_NONE progress=0 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.382+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" userId= Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.382+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" providers=3 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.382+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" plugins=24 Aug 27 19:37:11 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:11 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.386+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" state=STATUS_STOPPED positionMs=270 volume=100 Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.386+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.13:50501 @ 0x1ab2660" id=qobuz://song/53377462 title="Born to Run" Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.422+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://www.googleapis.com duration=147.604596ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.444+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://functions.volumio.cloud duration=168.32712ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.445+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://functions.volumio.cloud duration=170.19921ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.455+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://securetoken.googleapis.com duration=180.490617ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.516+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=http://cddb.volumio.org duration=240.907144ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.658+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=http://plugins.volumio.org duration=382.823138ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.799+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=524.143839ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.869+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://database.volumio.cloud duration=592.759892ms Aug 27 19:37:11 primo volumio5-onboarding[3822]: time=2026-08-27T19:37:11.973+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.13:50501 @ 0x1ab2660" latency=-1.04544ms timeout=10s endpoint=https://google.com duration=698.483396ms Aug 27 19:37:13 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:37:13 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:13 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:13 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:13 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:13 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:13 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:13 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:14 primo volumio[3534]: info: Executing endpoint metavolumio Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 19:37:14 primo volumio[3534]: info: Executing endpoint metavolumio Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:37:14 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:14 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:14 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:37:14 primo volumio[3534]: info: Received Get System Info Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:37:14 primo volumio[3534]: info: Discovery: Getting this device information Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::volumioGetState Aug 27 19:37:14 primo volumio[3534]: info: CorePlayQueue::getTrack 0 Aug 27 19:37:14 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:37:16 primo volumio[3534]: error: Failed request for metavolumio API Aug 27 19:37:16 primo volumio[3534]: error: Failed request for metavolumio API Aug 27 19:37:19 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 19:37:20 primo volumio[3534]: Searching all installed plugins Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: , search Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 27 19:37:20 primo volumio[3534]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 27 19:37:20 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 27 19:37:21 primo volumio[3534]: error: Failed request for metavolumio API Aug 27 19:37:21 primo volumio[3534]: error: Failed request for metavolumio API Aug 27 19:37:21 primo volumio[3534]: info: searchQOBUZUri took 1217 milliseconds Aug 27 19:37:22 primo volumio[3534]: info: SHOUTCAST sorgulanırken bir hata oluştu Aug 27 19:37:22 primo volumio[3534]: info: All search sources collected, pushing search results Aug 27 19:37:23 primo volumio[3534]: Searching all installed plugins Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: , search Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 27 19:37:23 primo volumio[3534]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 27 19:37:23 primo volumio[3534]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 27 19:37:24 primo volumio[3534]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 19:37:24 primo volumio[3534]: XMLStructuredError: Premature end of data in tag br line 187 (Line: 195, Column: 7) Aug 27 19:37:24 primo volumio[3534]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Aug 27 19:37:24 primo volumio[3534]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Aug 27 19:37:24 primo volumio[3534]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Aug 27 19:37:24 primo volumio[3534]: at /volumio/app/plugins/music_service/webradio/index.js:962:31 Aug 27 19:37:24 primo volumio[3534]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Aug 27 19:37:24 primo volumio[3534]: domain: 1, Aug 27 19:37:24 primo volumio[3534]: code: 77, Aug 27 19:37:24 primo volumio[3534]: level: 3, Aug 27 19:37:24 primo volumio[3534]: column: 7, Aug 27 19:37:24 primo volumio[3534]: file: '', Aug 27 19:37:24 primo volumio[3534]: line: 195, Aug 27 19:37:24 primo volumio[3534]: str1: 'br', Aug 27 19:37:24 primo volumio[3534]: str2: undefined, Aug 27 19:37:24 primo volumio[3534]: str3: undefined, Aug 27 19:37:24 primo volumio[3534]: int1: 187 Aug 27 19:37:24 primo volumio[3534]: } Aug 27 19:37:24 primo volumio[3534]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 19:37:24 primo sudo[11596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 19:36' Aug 27 19:37:24 primo sudo[11596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun May 17 17:32:08 UTC 2026" VOLUMIO_VERSION="4.158" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="43d420a3aa41c50690ebfe378df38e2b"