May 15 13:16:13 katana065 systemd[1]: systemd-timedated.service: Deactivated successfully. May 15 13:16:20 katana065 volumio[1135]: error: error May 15 13:16:21 katana065 volumio[1135]: error: error May 15 13:16:21 katana065 volumio[1135]: error: error May 15 13:16:59 katana065 kernel: hwmon hwmon1: Undervoltage detected! May 15 13:17:05 katana065 kernel: hwmon hwmon1: Voltage normalised May 15 13:17:11 katana065 volumio[1135]: error: error May 15 13:17:11 katana065 volumio[1135]: error: error May 15 13:17:11 katana065 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 15 13:17:11 katana065 dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.42' (uid=0 pid=2293 comm="timedatectl show --property=NTPSynchronized --valu") May 15 13:17:11 katana065 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 15 13:17:11 katana065 volumio[1135]: error: error May 15 13:17:12 katana065 dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.timedate1' May 15 13:17:12 katana065 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 15 13:17:12 katana065 setdatetime-helper.sh[2292]: Time is already synchronized. May 15 13:17:12 katana065 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 15 13:17:12 katana065 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 15 13:17:19 katana065 kernel: hwmon hwmon1: Undervoltage detected! May 15 13:17:21 katana065 volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 13:17:21 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 13:17:22 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 13:17:22 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 13:17:22 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 15 13:17:22 katana065 volumio[1135]: info: Received Get System Version May 15 13:17:22 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 13:17:24 katana065 kernel: hwmon hwmon1: Voltage normalised May 15 13:17:29 katana065 volumio[1135]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 15 13:17:29 katana065 volumio[1135]: info: System name has changed, restarting Shairport Sync May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 13:17:29 katana065 volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 13:17:29 katana065 volumio[1135]: info: Updating MyVolumio device info May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 13:17:29 katana065 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 13:17:29 katana065 vtcs[1780]: [2025-05-15 13:17:29.972] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 15 13:17:29 katana065 vtcs[1780]: [2025-05-15 13:17:29.972] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 15 13:17:29 katana065 vtcs[1780]: [2025-05-15 13:17:29.972] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe May 15 13:17:30 katana065 volumio[1135]: info: Discovery: Restarting Advertising due to device name change May 15 13:17:30 katana065 sudo[2358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 15 13:17:30 katana065 sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana065 sudo[2360]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 15 13:17:30 katana065 sudo[2360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana065 sudo[2363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 15 13:17:30 katana065 sudo[2363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana065 sudo[2360]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana065 volumio[1135]: info: Starting Shairport Sync May 15 13:17:30 katana065 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 15 13:17:30 katana065 systemd[1]: vtcs.service: Deactivated successfully. May 15 13:17:30 katana065 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 15 13:17:30 katana065 systemd[1]: vtcs.service: Consumed 1.027s CPU time. May 15 13:17:30 katana065 sudo[2367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts May 15 13:17:30 katana065 sudo[2367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana065 sudo[2367]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana065 volumio[1135]: info: Permissions for /etc/hosts set May 15 13:17:30 katana065 sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 13:17:30 katana065 sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana065 sudo[2358]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana065 sudo[2373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname katana066 May 15 13:17:30 katana065 sudo[2373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana066 volumio[1135]: info: Hostname now is katana066 May 15 13:17:30 katana066 sudo[2373]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana066 systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... May 15 13:17:30 katana066 kernel: hwmon hwmon1: Undervoltage detected! May 15 13:17:30 katana066 volumio[1135]: info: New hostname set May 15 13:17:30 katana066 sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ May 15 13:17:30 katana066 sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:30 katana066 sudo[2378]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana066 systemd[1]: fix-alpha-hosts.service: Deactivated successfully. May 15 13:17:30 katana066 systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. May 15 13:17:30 katana066 volumio[1135]: info: Permissions for /etc/avahi/services/volumio.service May 15 13:17:30 katana066 sudo[2363]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana066 avahi-daemon[575]: Files changed, reloading. May 15 13:17:30 katana066 volumio[1135]: info: Avahi name changed to katana066 May 15 13:17:30 katana066 avahi-daemon[575]: Service group file /services/volumio.service changed, reloading. May 15 13:17:30 katana066 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 15 13:17:30 katana066 systemd[1]: shairport-sync.service: Deactivated successfully. May 15 13:17:30 katana066 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 13:17:30 katana066 systemd[1]: shairport-sync.service: Consumed 3.458s CPU time. May 15 13:17:30 katana066 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 13:17:30 katana066 sudo[2369]: pam_unix(sudo:session): session closed for user root May 15 13:17:30 katana066 volumio[1135]: info: Shairport-Sync Started May 15 13:17:30 katana066 volumio[1135]: info: Successfully Updated MyVolumio device May 15 13:17:31 katana066 volumio[1135]: info: Discovery: A device disappeared from network May 15 13:17:31 katana066 volumio[1135]: info: Discovery: Device katana065 disappeared from network May 15 13:17:31 katana066 volumio[1135]: info: Discovery: A device disappeared from network May 15 13:17:31 katana066 volumio[1135]: info: Discovery: A device disappeared from network May 15 13:17:31 katana066 avahi-daemon[575]: Service "Katana066" (/services/volumio.service) successfully established. May 15 13:17:33 katana066 volumio[1135]: info: TidalConnect service stoped! May 15 13:17:33 katana066 volumio[1135]: info: TidalConnect service stoped! May 15 13:17:33 katana066 volumio[1135]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 15 13:17:33 katana066 volumio[1135]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 15 13:17:33 katana066 sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 15 13:17:33 katana066 sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 13:17:33 katana066 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 15 13:17:33 katana066 sudo[2409]: pam_unix(sudo:session): session closed for user root May 15 13:17:34 katana066 volumio[1135]: info: Executing endpoint tc_getconfig May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 15 13:17:34 katana066 vtcs[2412]: STARTING TidalConnect services, version: 1.5.0.45 May 15 13:17:34 katana066 vtcs[2412]: STARTED TidalConnect services. May 15 13:17:34 katana066 volumio[1135]: info: Executing endpoint tc_connect May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 15 13:17:34 katana066 volumio[1135]: info: Connecting to TidalConnect May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::servicePushState May 15 13:17:34 katana066 volumio[1135]: info: CoreStateMachine::pushState May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::volumioPushState May 15 13:17:34 katana066 volumio[1135]: info: MRS: Pushing multiroomSync output update for this device May 15 13:17:34 katana066 volumio[1135]: info: MRS: Pushing multiroomSync output May 15 13:17:34 katana066 volumio[1135]: info: CorePlayQueue::getTrack 0 May 15 13:17:34 katana066 volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::servicePushState May 15 13:17:34 katana066 volumio[1135]: info: CoreStateMachine::pushState May 15 13:17:34 katana066 volumio[1135]: info: CoreCommandRouter::volumioPushState May 15 13:17:34 katana066 volumio[1135]: info: MRS: Pushing multiroomSync output update for this device May 15 13:17:34 katana066 volumio[1135]: info: MRS: Pushing multiroomSync output May 15 13:17:34 katana066 volumio[1135]: info: CorePlayQueue::getTrack 0 May 15 13:17:34 katana066 volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 15 13:17:34 katana066 volumio[1135]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 May 15 13:17:34 katana066 volumio[1135]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 May 15 13:17:34 katana066 volumio[1135]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.iX7zzOtLcBMLNDwXTyjLuCdCamR2.a115510470a46341e350716c73cce937.state.status' May 15 13:17:34 katana066 volumio[1135]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.iX7zzOtLcBMLNDwXTyjLuCdCamR2.a115510470a46341e350716c73cce937.state.status' May 15 13:17:35 katana066 volumio[1135]: info: Discovery: Started advertising with name: Katana066 May 15 13:17:35 katana066 volumio[1135]: compat.c: read() failed: Resource temporarily unavailable May 15 13:17:35 katana066 volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 13:17:35 katana066 volumio[1135]: Error: dns service error: unknown May 15 13:17:35 katana066 volumio[1135]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 15 13:17:35 katana066 volumio[1135]: errorCode: -65537 May 15 13:17:35 katana066 volumio[1135]: } May 15 13:17:35 katana066 volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 13:17:37 katana066 sudo[2445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-15 13:16' May 15 13:17:37 katana066 sudo[2445]: 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="74e551e797a3a333b0b20739125f2f8b0b974aba" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 14 16:06:36 UTC 2025" VOLUMIO_VERSION="0.066" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="72f9e088cb36b840954a7bc22b48bde7"