-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Wed 2026-03-04 09:48:49 EST. -- Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.305-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.50.164:52286 Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.318-05:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.50.164:52286 @ 0x1ef0030" latency=-225.635991ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.319-05:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.50.164:52286 @ 0x1ef0030" latency=-225.165235ms timeout=10s Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.319-05:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" Mar 04 09:48:05 volumio volumio[1001]: info: Received Get System Info Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 09:48:05 volumio volumio[1001]: info: Discovery: Getting this device information Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:05 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.322-05:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" name=Volumio Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.322-05:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" language=en Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.324-05:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" timezone=America/Toronto Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.325-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" available=true connected=false macAddress= ip4Address= ip6Address= Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.327-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" available=true connected=true macAddress=dc:a6:32:17:d1:19 ip4Address=192.168.50.50/24 ip6Address= ssid=NettSh_nomap Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.328-05:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" setupComplete=true Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 09:48:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 09:48:05 volumio volumio[1001]: amixer -c 2 info | grep "snd_rpi_hifiberry_dacplusdsp" Mar 04 09:48:05 volumio volumio[1001]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 04 09:48:05 volumio volumio[1001]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 04 09:48:05 volumio volumio[1001]: Card hw:1 'Headphones'/'bcm2835 Headphones' Mar 04 09:48:05 volumio volumio[1001]: amixer -c 2 info | grep "snd_rpi_hifiberrydacplusdsp_sou" Mar 04 09:48:05 volumio volumio[1001]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberrydacplusdsp_soundcard' Mar 04 09:48:05 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:05.680-05:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" selectedOutputId=2 Mar 04 09:48:06 volumio volumio[1001]: info: Received Get System Info Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 09:48:06 volumio volumio[1001]: info: Discovery: Getting this device information Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.120-05:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" currentVersion=3.908 latestVersion=3.908 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.121-05:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" status=UPDATE_STATUS_NONE progress=0 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.121-05:00 level=INFO msg="emitting user changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" userId=xd0mMgvLxhVymfQb8fcof27tVp43 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.121-05:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" providers=9 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.121-05:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" plugins=54 Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.123-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" state=STATUS_PLAYING positionMs=132727 volume=21 Mar 04 09:48:06 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:06.123-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.164:52286 @ 0x1ef0030" id="mnt/NAS/prime_ham/VA-50.Feminine.Voices.To.Test.Your.Loudspeakers.24bit.44-192kHz.FLAC.tracks-SPMusic/FLAC (24bit-44.1kHz)/Carmen McRae - Whatever Lola Wants.flac" title="Whatever Lola Wants" Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 09:48:06 volumio volumio[1001]: info: Discovery: Getting this device information Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 09:48:06 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.50.50:3000 from 192.168.50.164 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 09:48:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 09:48:08 volumio volumio5-onboarding[1199]: time=2026-03-04T09:48:08.283-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.50.164:52286 @ 0x1ef0030" latency=366.902186ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 04 09:48:10 volumio sudo[26016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 09:48:10 volumio sudo[26016]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 09:48:10 volumio sudo[26016]: pam_unix(sudo:session): session closed for user root Mar 04 09:48:10 volumio sudo[26019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 09:48:10 volumio sudo[26019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 09:48:10 volumio sudo[26019]: pam_unix(sudo:session): session closed for user root Mar 04 09:48:10 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.50.50 from 192.168.50.164 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 04 09:48:11 volumio sudo[26065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 09:48:11 volumio sudo[26065]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 09:48:11 volumio sudo[26065]: pam_unix(sudo:session): session closed for user root Mar 04 09:48:11 volumio sudo[26068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 09:48:11 volumio sudo[26068]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 09:48:11 volumio sudo[26068]: pam_unix(sudo:session): session closed for user root Mar 04 09:48:12 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.50.50 from 192.168.50.164 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 09:48:12 volumio volumio[1001]: info: Received Get System Info Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 09:48:12 volumio volumio[1001]: info: Discovery: Getting this device information Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:12 volumio volumio[1001]: info: Listing playlists Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 09:48:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 09:48:13 volumio volumio[1001]: info: Received Get System Info Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 09:48:13 volumio volumio[1001]: info: Discovery: Getting this device information Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 04 09:48:13 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 04 09:48:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 09:48:16 volumio volumio[1001]: info: Saving local item Loungey Mar 04 09:48:16 volumio volumio[1001]: info: Pushing Favourites {"uri":"mnt/NAS/prime_ham/VA-50.Feminine.Voices.To.Test.Your.Loudspeakers.24bit.44-192kHz.FLAC.tracks-SPMusic/FLAC (24bit-44.1kHz)/Carmen McRae - Whatever Lola Wants.flac","favourite":false} Mar 04 09:48:16 volumio volumio[1001]: info: Listing playlists Mar 04 09:48:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 09:48:22 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 09:48:48 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Mar 04 09:48:48 volumio volumio[1001]: info: CorePlayQueue::getTrack 11 Mar 04 09:48:48 volumio volumio[1001]: info: Prefetching next song Mar 04 09:48:48 volumio volumio[1001]: info: DOING PREFETCH IN MPD Mar 04 09:48:48 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "NAS/prime_ham/VA-50.Feminine.Voices.To.Test.Your.Loudspeakers.24bit.44-192kHz.FLAC.tracks-SPMusic/FLAC (24bit-44.1kHz)/Celeste - Love Is Back.flac" Mar 04 09:48:48 volumio volumio[1001]: info: Mar 04 09:48:48 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Mar 04 09:48:48 volumio volumio[1001]: info: Ignoring MPD Status Update Mar 04 09:48:48 volumio volumio[1001]: info: sendMpdCommand add "NAS/prime_ham/VA-50.Feminine.Voices.To.Test.Your.Loudspeakers.24bit.44-192kHz.FLAC.tracks-SPMusic/FLAC (24bit-44.1kHz)/Celeste - Love Is Back.flac" took 2 milliseconds Mar 04 09:48:48 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 04 09:48:48 volumio volumio[1001]: info: Mar 04 09:48:48 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Mar 04 09:48:48 volumio volumio[1001]: info: Ignoring MPD Status Update Mar 04 09:48:48 volumio volumio[1001]: info: Mar 04 09:48:48 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Mar 04 09:48:48 volumio volumio[1001]: info: Ignoring MPD Status Update Mar 04 09:48:48 volumio volumio[1001]: info: ------------------------------ 3ms Mar 04 09:48:48 volumio volumio[1001]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 04 09:48:48 volumio volumio[1001]: info: ------------------------------ 2ms Mar 04 09:48:48 volumio volumio[1001]: info: ------------------------------ 1ms Mar 04 09:48:49 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 09:48:49 volumio volumio[1001]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Mar 04 09:48:49 volumio volumio[1001]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Mar 04 09:48:49 volumio volumio[1001]: errno: -3007, Mar 04 09:48:49 volumio volumio[1001]: code: 'ENOTFOUND', Mar 04 09:48:49 volumio volumio[1001]: syscall: 'getaddrinfo', Mar 04 09:48:49 volumio volumio[1001]: hostname: 'lastfm.freetls.fastly.net' Mar 04 09:48:49 volumio volumio[1001]: } Mar 04 09:48:49 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 09:48:49 volumio sudo[26215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 09:47 Mar 04 09:48:49 volumio sudo[26215]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"