-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2026-08-31 14:09:38 CEST. -- Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.472Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=56.296305ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.492Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.506Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=13.782649ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.516Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=23.054084ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.519Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=http://pushupdates.volumio.org duration=25.42495ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.523Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=29.448564ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.556Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://google.com duration=62.654911ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.609Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://www.googleapis.com duration=116.479216ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.627Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://securetoken.googleapis.com duration=134.407664ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.637Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://functions.volumio.cloud duration=143.302697ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.638Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=144.912216ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.648Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://functions.volumio.cloud duration=154.417034ms Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.672Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=https://database.volumio.cloud duration=178.031531ms Aug 31 14:08:15 volumiopa sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:08:15 volumiopa sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:15 volumiopa sudo[3265]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:15 volumiopa sudo[3267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:08:15 volumiopa sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:15 volumiopa sudo[3267]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:15 volumiopa volumio[987]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (Linux; Android 16; RMX5210 Build/BP2A.250605.015; 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: 8 Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.878Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=http://cddb.volumio.org duration=384.275543ms Aug 31 14:08:15 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:08:15 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:15.985Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:39640,00:00:00:00:00:00%01 @ 0x28011d0" latency=40.70418ms timeout=10s endpoint=http://plugins.volumio.org duration=491.41124ms Aug 31 14:08:16 volumiopa sudo[3271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:08:16 volumiopa sudo[3271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:16 volumiopa sudo[3271]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 14:08:16 volumiopa sudo[3273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:08:16 volumiopa sudo[3273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:16 volumiopa sudo[3273]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:16 volumiopa volumio[987]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (Linux; Android 16; RMX5210 Build/BP2A.250605.015; 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: 9 Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:16 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 31 14:08:16 volumiopa volumio[987]: info: CoreStateMachine::getQueue Aug 31 14:08:16 volumiopa volumio[987]: info: CorePlayQueue::getQueue Aug 31 14:08:16 volumiopa volumio[987]: info: Listing playlists Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 14:08:16 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:16 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:16 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:16 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:16 volumiopa volumio[987]: info: FusionDsp - Aug 31 14:08:16 volumiopa volumio[987]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Aug 31 14:08:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 14:08:17 volumiopa kernel: hwmon hwmon1: Undervoltage detected! Aug 31 14:08:17 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:08:18 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:18 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:18 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:08:18 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:18 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:18 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:18 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:23 volumiopa kernel: hwmon hwmon1: Voltage normalised Aug 31 14:08:26 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.902Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.113:39640 Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.902Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.113:39640 Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.907Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.113:57626 Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.959Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.968Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.113:57626 @ 0x2b868a0" latency=38.93111ms timeout=20s Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.968Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" Aug 31 14:08:27 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:27 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:27 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.974Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=15.211128ms Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.975Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" name=VolumioPa Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.977Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" language=nl Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.981Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" timezone=Europe/Amsterdam Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.982Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.991Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=30.78366ms Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.991Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=31.76308ms Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.991Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=http://pushupdates.volumio.org duration=31.492561ms Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.992Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" available=true connected=true macAddress=b8:27:eb:24:15:00 ip4Address=192.168.1.126/24 ip6Address= ssid="Fam Le Granse 2.4ghz" Aug 31 14:08:27 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:27.993Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" setupComplete=true Aug 31 14:08:27 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.024Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://google.com duration=65.043073ms Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 14:08:28 volumiopa volumio[987]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.086Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://www.googleapis.com duration=126.389355ms Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.093Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://functions.volumio.cloud duration=133.112582ms Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.094Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://functions.volumio.cloud duration=134.093876ms Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.109Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=148.807561ms Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.110Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://securetoken.googleapis.com duration=150.724994ms Aug 31 14:08:28 volumiopa volumio[987]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.156Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=http://cddb.volumio.org duration=196.263276ms Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.158Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=https://database.volumio.cloud duration=197.591129ms Aug 31 14:08:28 volumiopa volumio[987]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 31 14:08:28 volumiopa volumio[987]: amixer -c 2 info | grep "HiFiBerry Digi" Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.259Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=37.232794ms timeout=10s endpoint=http://plugins.volumio.org duration=298.423762ms Aug 31 14:08:28 volumiopa volumio[987]: amixer -c 2 info | grep "snd_rpi_hifiberry_digi" Aug 31 14:08:28 volumiopa volumio[987]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.401Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" selectedOutputId=2 Aug 31 14:08:28 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:28 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:28 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.426Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" currentVersion=3.912 latestVersion=3.912 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.427Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" status=UPDATE_STATUS_NONE progress=0 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.427Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" userId=SmIpDUgXbCatXf0hum7jTeRHxZs1 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.427Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" providers=9 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.428Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" plugins=54 Aug 31 14:08:28 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:28 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.434Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 14:08:28 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:28.435Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.113:57626 @ 0x2b868a0" id= title= Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:29 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:29 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:29 volumiopa dbus-daemon[570]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1605 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=760 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Aug 31 14:08:29 volumiopa volumio[987]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.113 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 14:08:29 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:08:34 volumiopa volumio[987]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 14:08:34 volumiopa volumio[987]: info: Received Get System Version Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:08:34 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:34 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:34 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:34 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.051Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.113:57626 @ 0x2b868a0" latency=55.531896ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.143Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.194Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=51.311263ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.197Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=http://pushupdates.volumio.org duration=52.643388ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.197Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=52.919792ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.197Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=53.476923ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.227Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://google.com duration=83.608352ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.273Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://www.googleapis.com duration=130.057414ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.292Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://securetoken.googleapis.com duration=148.877943ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.294Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://functions.volumio.cloud duration=150.35413ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.299Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=155.783618ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.334Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://database.volumio.cloud duration=190.261212ms Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.395Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=https://functions.volumio.cloud duration=251.691562ms Aug 31 14:08:47 volumiopa sudo[3387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:08:47 volumiopa sudo[3387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:47 volumiopa sudo[3387]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:47 volumiopa sudo[3389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:08:47 volumiopa sudo[3389]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:47 volumiopa sudo[3389]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:47 volumiopa volumio[987]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (Linux; Android 16; RMX5210 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.548Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=http://cddb.volumio.org duration=404.268695ms Aug 31 14:08:47 volumiopa sudo[3393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:08:47 volumiopa sudo[3393]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:47 volumiopa sudo[3393]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:47 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:47.657Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.113:57626 @ 0x2b868a0" latency=103.096742ms timeout=10s endpoint=http://plugins.volumio.org duration=513.230443ms Aug 31 14:08:47 volumiopa sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:08:47 volumiopa sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:08:47 volumiopa sudo[3395]: pam_unix(sudo:session): session closed for user root Aug 31 14:08:47 volumiopa volumio[987]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (Linux; Android 16; RMX5210 Build/BP2A.250605.015; 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: 8 Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:47 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 14:08:47 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:47 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:47 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:47 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:47 volumiopa volumio[987]: info: Listing playlists Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 14:08:47 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:47 volumiopa volumio[987]: info: FusionDsp - Aug 31 14:08:48 volumiopa volumio[987]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:08:49 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:49 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:49 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:49 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:08:50 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:08:50 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:08:50 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:08:50 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 31 14:08:50 volumiopa volumio[987]: info: CoreStateMachine::getQueue Aug 31 14:08:50 volumiopa volumio[987]: info: CorePlayQueue::getQueue Aug 31 14:08:54 volumiopa volumio5-onboarding[1605]: time=2026-08-31T12:08:54.198Z level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Aug 31 14:08:54 volumiopa dbus-daemon[570]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1605 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=760 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: info: Retrieving Cloud Streaming UI Aug 31 14:08:56 volumiopa volumio[987]: info: Getting Tidal Cloud Configuration Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: info: Getting Qobuz Cloud Configuration Aug 31 14:08:56 volumiopa volumio[987]: info: Asking plugin for UI Config Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: info: Getting Spotify Cloud Configuration Aug 31 14:08:56 volumiopa volumio[987]: info: Asking plugin for UI Config Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: info: Saving Spotify Acccount Aug 31 14:08:56 volumiopa volumio[987]: info: Got Tidal Cloud Configuration Aug 31 14:08:56 volumiopa volumio[987]: info: Got it Aug 31 14:08:56 volumiopa volumio[987]: info: Got it Aug 31 14:08:56 volumiopa volumio[987]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 14:08:56 volumiopa volumio[987]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:08:56 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 14:08:57 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 14:09:00 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:04 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:09:05 volumiopa volumio[987]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 14:09:05 volumiopa volumio[987]: info: Received Get System Version Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:09:05 volumiopa volumio[987]: info: Received Get System Info Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:09:05 volumiopa volumio[987]: info: Discovery: Getting this device information Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::volumioGetState Aug 31 14:09:05 volumiopa volumio[987]: info: CorePlayQueue::getTrack 0 Aug 31 14:09:05 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:09:08 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:12 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:15 volumiopa volumio[987]: info: Disabling MyMusic plugin upnp_browser Aug 31 14:09:15 volumiopa volumio[987]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Aug 31 14:09:15 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:09:15 volumiopa volumio[987]: Cannot find translation for source 80s80s Radio Aug 31 14:09:15 volumiopa volumio[987]: info: Disabling plugin upnp_browser Aug 31 14:09:15 volumiopa volumio[987]: info: Done. Aug 31 14:09:16 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:20 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:23 volumiopa volumio[987]: info: Enabling MyMusic plugin upnp_browser Aug 31 14:09:23 volumiopa volumio[987]: info: Enabling plugin upnp_browser Aug 31 14:09:23 volumiopa volumio[987]: info: Loading plugin "upnp_browser"... Aug 31 14:09:23 volumiopa volumio[987]: info: Starting UPNP Browser Aug 31 14:09:23 volumiopa volumio[987]: info: PLUGIN START: upnp_browser Aug 31 14:09:23 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:09:23 volumiopa volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 14:09:23 volumiopa volumio[987]: info: [1788178163521] CoreMusicLibrary::Adding element Media Servers Aug 31 14:09:23 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:09:23 volumiopa volumio[987]: Cannot find translation for source 80s80s Radio Aug 31 14:09:23 volumiopa volumio[987]: info: UPNP Browser: Client initialized successfully Aug 31 14:09:23 volumiopa volumio[987]: info: Done. Aug 31 14:09:24 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:28 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:32 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:33 volumiopa volumio[987]: info: Disabling MyMusic plugin upnp Aug 31 14:09:33 volumiopa sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 31 14:09:33 volumiopa sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:09:33 volumiopa systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 31 14:09:33 volumiopa volumio[987]: error: Upnp client error: Error: This socket has been ended by the other party Aug 31 14:09:36 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 14:09:37 volumiopa volumio[987]: info: Enabling MyMusic plugin upnp Aug 31 14:09:37 volumiopa volumio[987]: info: Enabling plugin upnp Aug 31 14:09:37 volumiopa volumio[987]: info: Loading plugin "upnp"... Aug 31 14:09:37 volumiopa volumio[987]: info: [1788178177992] Starting Upmpd Daemon Aug 31 14:09:37 volumiopa volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 14:09:38 volumiopa volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:09:38 volumiopa volumio[987]: Error: listen EADDRINUSE: address already in use :::6599 Aug 31 14:09:38 volumiopa volumio[987]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 31 14:09:38 volumiopa volumio[987]: at listenInCluster (net.js:1366:12) Aug 31 14:09:38 volumiopa volumio[987]: at Server.listen (net.js:1452:7) Aug 31 14:09:38 volumiopa volumio[987]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 31 14:09:38 volumiopa volumio[987]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 31 14:09:38 volumiopa volumio[987]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 31 14:09:38 volumiopa volumio[987]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 31 14:09:38 volumiopa volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 31 14:09:38 volumiopa volumio[987]: code: 'EADDRINUSE', Aug 31 14:09:38 volumiopa volumio[987]: errno: -98, Aug 31 14:09:38 volumiopa volumio[987]: syscall: 'listen', Aug 31 14:09:38 volumiopa volumio[987]: address: '::', Aug 31 14:09:38 volumiopa volumio[987]: port: 6599 Aug 31 14:09:38 volumiopa volumio[987]: } Aug 31 14:09:38 volumiopa volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:09:38 volumiopa sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 14:08 Aug 31 14:09:38 volumiopa sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"