-- Logs begin at Wed 2026-10-07 17:43:28 CDT, end at Thu 2026-10-15 17:17:46 CDT. -- Oct 15 17:16:53 volumio wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Oct 15 17:17:09 volumio wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Oct 15 17:17:24 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:24.886-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.96.124:61058 Oct 15 17:17:24 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:24.888-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.96.124:61057 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.023-05:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.96.124:61058 @ 0x212c8d0" latency=11.451191ms platform=PLATFORM_IOS version=6.260930.0 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.023-05:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.96.124:61058 @ 0x212c8d0" latency=10.78226ms timeout=20s Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.023-05:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" Oct 15 17:17:25 volumio volumio[1089]: info: Received Get System Info Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:17:25 volumio volumio[1089]: info: Discovery: Getting this device information Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Oct 15 17:17:25 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.027-05:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" name=Volumio Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.027-05:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" language=en Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.029-05:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" timezone=America/Chicago Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.030-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" available=true connected=true macAddress=dc:a6:32:68:37:5d ip4Address=192.168.96.198/24 ip6Address= Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.032-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" available=true connected=true macAddress=dc:a6:32:68:37:5e ip4Address=192.168.96.197/24 ip6Address= ssid=SoComplimentary Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.033-05:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" setupComplete=true Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 17:17:25 volumio volumio[1089]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: amixer -c 2 info | grep "HiFiBerry DAC2 HD" Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: amixer -c 2 info | grep "snd_rpi_hifiberry_dacplushd" Oct 15 17:17:25 volumio volumio[1089]: xcb_connection_has_error() returned true Oct 15 17:17:25 volumio volumio[1089]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplushd' Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.513-05:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" selectedOutputId=2 Oct 15 17:17:25 volumio volumio[1089]: info: Received Get System Info Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:17:25 volumio volumio[1089]: info: Discovery: Getting this device information Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Oct 15 17:17:25 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.524-05:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" currentVersion=3.912 latestVersion=3.912 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.524-05:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" status=UPDATE_STATUS_NONE progress=0 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.524-05:00 level=INFO msg="emitting user changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" userId= Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.524-05:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" providers=3 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.524-05:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" plugins=54 Oct 15 17:17:25 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Oct 15 17:17:25 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.527-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" state=STATUS_STOPPED positionMs=250 volume=100 Oct 15 17:17:25 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:25.527-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.96.124:61058 @ 0x212c8d0" id=http://api.somafm.com/groovesalad.pls title="SomaFM: Groove Salad" Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:17:26 volumio volumio[1089]: info: Discovery: Getting this device information Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Oct 15 17:17:26 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:17:26 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:26.483-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.96.124:61057 Oct 15 17:17:26 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:26.483-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.96.124:61057 Oct 15 17:17:26 volumio volumio[1089]: verbose: New Socket.io Connection to 192.168.96.198:3000 from 192.168.96.124 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 15 17:17:26 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:26.599-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.96.124:61192 Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 17:17:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.183-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.96.124:61058 @ 0x212c8d0" latency=889.696µs timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.198-05:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.309-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=110.914911ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.388-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://www.googleapis.com duration=190.499892ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.507-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://database.volumio.cloud duration=308.870202ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.525-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=325.906879ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.556-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://google.com duration=357.700357ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.585-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=http://plugins.volumio.org duration=386.449377ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.604-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://securetoken.googleapis.com duration=404.676593ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.681-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://functions.volumio.cloud duration=482.414989ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.710-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=http://pushupdates.volumio.org duration=511.370932ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.713-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://functions.volumio.cloud duration=513.731455ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.836-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=636.432059ms Oct 15 17:17:27 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:27.845-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=645.765784ms Oct 15 17:17:28 volumio volumio5-onboarding[1321]: time=2026-10-15T17:17:28.313-05:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.96.124:61058 @ 0x212c8d0" latency=3.187025ms timeout=10s endpoint=http://cddb.volumio.org duration=1.114695104s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 17:17:28 volumio sudo[9874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:17:28 volumio sudo[9874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 17:17:28 volumio sudo[9874]: pam_unix(sudo:session): session closed for user root Oct 15 17:17:28 volumio sudo[9877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:17:28 volumio sudo[9877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 17:17:28 volumio sudo[9877]: pam_unix(sudo:session): session closed for user root Oct 15 17:17:28 volumio volumio[1089]: verbose: New Socket.io Connection to 192.168.96.198 from 192.168.96.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 15 17:17:28 volumio sudo[9880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:17:28 volumio sudo[9880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 17:17:28 volumio sudo[9880]: pam_unix(sudo:session): session closed for user root Oct 15 17:17:28 volumio sudo[9883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:17:28 volumio sudo[9883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 17:17:28 volumio sudo[9883]: pam_unix(sudo:session): session closed for user root Oct 15 17:17:28 volumio volumio[1089]: verbose: New Socket.io Connection to 192.168.96.198 from 192.168.96.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Oct 15 17:17:29 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 17:17:29 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 15 17:17:29 volumio volumio[1089]: info: Listing playlists Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 17:17:29 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 17:17:31 volumio wpa_supplicant[903]: RRM: Ignoring radio measurement request: Not RRM network Oct 15 17:17:46 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 15 17:17:46 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 15 17:17:46 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:17:46 volumio volumio[1089]: TypeError: Cannot read property 'then' of undefined Oct 15 17:17:46 volumio volumio[1089]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 15 17:17:46 volumio volumio[1089]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Oct 15 17:17:46 volumio volumio[1089]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Oct 15 17:17:46 volumio volumio[1089]: at Socket.emit (events.js:315:20) Oct 15 17:17:46 volumio volumio[1089]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 15 17:17:46 volumio volumio[1089]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 15 17:17:46 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:17:46 volumio sudo[9924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-15 17:16 Oct 15 17:17:46 volumio sudo[9924]: 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"