Dec 22 13:34:10 volumio sudo[1940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 13:34:10 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:10 volumio sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 13:34:10 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:10 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:10 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:10 volumio volumio[1171]: verbose: New Socket.io Connection to 192.168.1.48 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 22 13:34:11 volumio volumio[1171]: info: Received Get System Info Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:34:11 volumio volumio[1171]: info: Discovery: Getting this device information Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Dec 22 13:34:11 volumio volumio[1171]: info: CorePlayQueue::getTrack 0 Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:34:11 volumio volumio-remote-updater[607]: Test mode disabled Dec 22 13:34:11 volumio volumio-remote-updater[607]: Alpha mode disabled Dec 22 13:34:11 volumio volumio-remote-updater[607]: Alpha legacy test mode disabled Dec 22 13:34:11 volumio volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:34:11 volumio volumio[1171]: info: Retrieving Cloud Streaming UI Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 13:34:12 volumio volumio[1171]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:34:12 volumio volumio[1171]: info: Getting Tidal Cloud Configuration Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:34:12 volumio volumio[1171]: info: Getting Qobuz Cloud Configuration Dec 22 13:34:12 volumio volumio[1171]: info: Asking plugin for UI Config Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:34:12 volumio volumio[1171]: info: Getting Spotify Cloud Configuration Dec 22 13:34:12 volumio volumio[1171]: info: Asking plugin for UI Config Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:34:12 volumio volumio[1171]: info: Saving Spotify Acccount Dec 22 13:34:12 volumio volumio[1171]: info: Got Tidal Cloud Configuration Dec 22 13:34:12 volumio volumio[1171]: info: Got it Dec 22 13:34:12 volumio volumio[1171]: info: Got it Dec 22 13:34:12 volumio volumio[1171]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 13:34:12 volumio volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:34:12 volumio volumio[1171]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 22 13:34:13 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 22 13:34:13 volumio volumio[1171]: info: Loading i18n strings for locale en Dec 22 13:34:13 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 22 13:34:13 volumio volumio[1171]: info: Setting timezone to America/Chicago Dec 22 13:34:14 volumio sudo[1954]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 22 13:34:14 volumio sudo[1954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:14 volumio sudo[1954]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:14 volumio sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Dec 22 13:34:14 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:14 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:14 volumio sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 22 13:34:14 volumio sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:14 volumio sudo[1962]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:15 volumio sudo[1966]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Dec 22 13:34:15 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:15 volumio dbus-daemon[577]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1967 comm="/usr/bin/timedatectl set-timezone America/Chicago") Dec 22 13:34:15 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 22 13:34:16 volumio dbus-daemon[577]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 22 13:34:16 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 22 13:34:16 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:16 volumio volumio[1171]: info: Fetching Streaming Services browse cache Dec 22 13:34:17 volumio volumio[1171]: Updating browse sources language Dec 22 13:34:17 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:34:18 volumio upmpdcli[1467]: :2:../libupnpp/upnpplib.cxx:345::LibUPnP::setupWebServer(): UpnpRegisterRootDevice2 :-204: UPNP_E_SOCKET_CONNECT description http://192.168.211.1:49152/uuid-04255cb4-11f0-58db-0053-2ccf67c2ebc3/description.xml Dec 22 13:34:18 volumio upmpdcli[1467]: :2:../libupnpp/device/devdevice.cxx:334::UpnpDevice: libupnp can't start service. Err -204 Dec 22 13:34:18 volumio upmpdcli[1467]: :2:../libupnpp/device/devdevice.cxx:749::Device would not start Dec 22 13:34:20 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 22 13:34:20 volumio volumio[1171]: info: Loading i18n strings for locale en Dec 22 13:34:20 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 22 13:34:20 volumio volumio[1171]: info: Setting timezone to America/Chicago Dec 22 13:34:20 volumio sudo[1976]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 22 13:34:20 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:20 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:20 volumio sudo[1993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Chicago /etc/localtime Dec 22 13:34:20 volumio sudo[1993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:20 volumio sudo[1993]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:21 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 22 13:34:21 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:21 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:21 volumio sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Chicago Dec 22 13:34:21 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:21 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:21 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 22 13:34:21 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 22 13:34:21 volumio volumio[1171]: info: Fetching Streaming Services browse cache Dec 22 13:34:21 volumio sudo[2004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 13:34:21 volumio sudo[2004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:22 volumio volumio[1171]: Updating browse sources language Dec 22 13:34:22 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:34:22 volumio sudo[2004]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:34:29 volumio volumio[1171]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 13:34:29 volumio volumio[1171]: info: Received Get System Version Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 13:34:29 volumio volumio[1171]: info: Received Get System Info Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:34:29 volumio volumio[1171]: info: Discovery: Getting this device information Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::volumioGetState Dec 22 13:34:29 volumio volumio[1171]: info: CorePlayQueue::getTrack 0 Dec 22 13:34:29 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:34:46 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 22 13:34:46 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 13:34:46 volumio volumio-remote-updater[607]: Test mode disabled Dec 22 13:34:46 volumio volumio-remote-updater[607]: Alpha mode disabled Dec 22 13:34:46 volumio volumio-remote-updater[607]: Alpha legacy test mode disabled Dec 22 13:34:46 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 22 13:34:46 volumio volumio[1171]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 22 13:34:46 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 22 13:34:50 volumio volumio[1171]: info: System name has changed, restarting Shairport Sync Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:34:50 volumio volumio[1171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:34:50 volumio volumio[1171]: info: Discovery: Restarting Advertising due to device name change Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 22 13:34:50 volumio sudo[2046]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 22 13:34:50 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 22 13:34:50 volumio upmpdcli[1467]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:34:50 volumio upmpdcli[1467]: :2:../src/ohmetacache.cxx:94::dmcacheSaveWorker: can't get task from queue Dec 22 13:34:50 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 13:34:50 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:50 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Dec 22 13:34:50 volumio systemd[1]: upmpdcli.service: Consumed 24.196s CPU time. Dec 22 13:34:51 volumio volumio[1171]: info: Starting Shairport Sync Dec 22 13:34:51 volumio sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 22 13:34:51 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:51 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:51 volumio volumio[1171]: info: Permissions for /etc/hosts set Dec 22 13:34:51 volumio sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:34:51 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:51 volumio volumio[1171]: info: Hostname now is musicbox Dec 22 13:34:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:34:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:34:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:34:51 volumio systemd[1]: shairport-sync.service: Consumed 2.114s CPU time. Dec 22 13:34:51 volumio sudo[2063]: volumio : unable to resolve host volumio: System error Dec 22 13:34:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:34:51 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:51 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 22 13:34:51 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 22 13:34:51 volumio volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 13:34:51 volumio sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname musicbox Dec 22 13:34:51 volumio sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:51 volumio volumio[1171]: info: Discovery: A device disappeared from network Dec 22 13:34:51 volumio volumio[1171]: info: Discovery: Device volumio disappeared from network Dec 22 13:34:51 musicbox sudo[2063]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:51 musicbox volumio[1171]: info: Shairport-Sync Started Dec 22 13:34:51 musicbox volumio[1171]: info: Discovery: A device disappeared from network Dec 22 13:34:51 musicbox sudo[2078]: volumio : unable to resolve host volumio: System error Dec 22 13:34:51 musicbox sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 22 13:34:51 musicbox sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:34:51 musicbox sudo[2078]: pam_unix(sudo:session): session closed for user root Dec 22 13:34:51 musicbox volumio-remote-updater[607]: Test mode disabled Dec 22 13:34:51 musicbox volumio-remote-updater[607]: Alpha mode disabled Dec 22 13:34:51 musicbox volumio-remote-updater[607]: Alpha legacy test mode disabled Dec 22 13:34:52 musicbox volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 22 13:34:52 musicbox volumio[1171]: info: Permissions for /etc/avahi/services/volumio.service Dec 22 13:34:52 musicbox volumio[1171]: info: New hostname set Dec 22 13:34:52 musicbox avahi-daemon[576]: Files changed, reloading. Dec 22 13:34:52 musicbox avahi-daemon[576]: Loading service file /services/volumio.service. Dec 22 13:34:52 musicbox volumio[1171]: info: Avahi name changed to musicbox Dec 22 13:34:52 musicbox volumio[1171]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 22 13:34:52 musicbox volumio[1171]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 22 13:34:53 musicbox avahi-daemon[576]: Service "Musicbox" (/services/volumio.service) successfully established. Dec 22 13:34:55 musicbox volumio[1171]: info: Discovery: Started advertising with name: Musicbox Dec 22 13:34:56 musicbox volumio[1171]: compat.c: read() failed: Resource temporarily unavailable Dec 22 13:34:56 musicbox volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:34:56 musicbox volumio[1171]: Error: dns service error: unknown Dec 22 13:34:56 musicbox volumio[1171]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 22 13:34:56 musicbox volumio[1171]: errorCode: -65537 Dec 22 13:34:56 musicbox volumio[1171]: } Dec 22 13:34:56 musicbox volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:35:05 musicbox sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 13:34' Dec 22 13:35:05 musicbox sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"