May 20 13:19:06 065piano21 volumio[1100]: error: error May 20 13:19:08 065piano21 wpa_supplicant[1017]: wlan0: WPA: Group rekeying completed with f0:b0:14:f4:75:d0 [GTK=CCMP] May 20 13:19:17 065piano21 go-librespot[1367]: time="2025-05-20T13:19:17+02:00" level=trace msg="received accesspoint ping" May 20 13:19:17 065piano21 go-librespot[1367]: time="2025-05-20T13:19:17+02:00" level=trace msg="received accesspoint pong ack" May 20 13:19:17 065piano21 go-librespot[1367]: time="2025-05-20T13:19:17+02:00" level=trace msg="sent dealer ping" May 20 13:19:17 065piano21 go-librespot[1367]: time="2025-05-20T13:19:17+02:00" level=trace msg="received dealer pong" May 20 13:19:17 065piano21 systemd[1]: systemd-timedated.service: Deactivated successfully. May 20 13:19:47 065piano21 go-librespot[1367]: time="2025-05-20T13:19:47+02:00" level=trace msg="sent dealer ping" May 20 13:19:47 065piano21 go-librespot[1367]: time="2025-05-20T13:19:47+02:00" level=trace msg="received dealer pong" May 20 13:19:50 065piano21 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 20 13:19:50 065piano21 dbus-daemon[610]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.45' (uid=0 pid=3038 comm="timedatectl show --property=NTPSynchronized --valu") May 20 13:19:50 065piano21 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 20 13:19:50 065piano21 dbus-daemon[610]: [system] Successfully activated service 'org.freedesktop.timedate1' May 20 13:19:50 065piano21 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 20 13:19:50 065piano21 setdatetime-helper.sh[3037]: Time is not synchronized. Attempting to sync... May 20 13:19:50 065piano21 sudo[3046]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 20 May 2025 11:19:50 GMT#015' May 20 13:19:50 065piano21 sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 20 13:19:50 065piano21 setdatetime-helper.sh[3047]: Tue May 20 13:19:50 CEST 2025 May 20 13:19:50 065piano21 setdatetime-helper.sh[3037]: Time synchronized successfully. May 20 13:19:50 065piano21 sudo[3046]: pam_unix(sudo:session): session closed for user root May 20 13:19:50 065piano21 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 20 13:19:50 065piano21 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 20 13:19:50 065piano21 systemd-journald[335]: Time jumped backwards, rotating. May 20 13:19:53 065piano21 volumio[1100]: error: error May 20 13:20:16 065piano21 go-librespot[1367]: time="2025-05-20T13:20:16+02:00" level=trace msg="sent dealer ping" May 20 13:20:16 065piano21 go-librespot[1367]: time="2025-05-20T13:20:16+02:00" level=trace msg="received dealer pong" May 20 13:20:19 065piano21 systemd[1]: systemd-timedated.service: Deactivated successfully. May 20 13:20:32 065piano21 volumio[1100]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 20 13:20:32 065piano21 volumio[1100]: info: System name has changed, restarting Shairport Sync May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 13:20:32 065piano21 volumio[1100]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 13:20:32 065piano21 volumio[1100]: info: Updating MyVolumio device info May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 13:20:32 065piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 13:20:32 065piano21 vtcs[1778]: [2025-05-20 13:20:32.645] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 20 13:20:32 065piano21 vtcs[1778]: [2025-05-20 13:20:32.646] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 20 13:20:32 065piano21 volumio[1100]: info: Discovery: Restarting Advertising due to device name change May 20 13:20:32 065piano21 sudo[3199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 13:20:32 065piano21 sudo[3199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 065piano21 sudo[3201]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 20 13:20:32 065piano21 sudo[3201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 065piano21 sudo[3201]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 065piano21 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 20 13:20:32 065piano21 systemd[1]: vtcs.service: Killing process 1783 (vtcs) with signal SIGKILL. May 20 13:20:32 065piano21 sudo[3204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 20 13:20:32 065piano21 sudo[3204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 065piano21 systemd[1]: vtcs.service: Deactivated successfully. May 20 13:20:32 065piano21 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 20 13:20:32 065piano21 volumio[1100]: info: Starting Shairport Sync May 20 13:20:32 065piano21 sudo[3207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts May 20 13:20:32 065piano21 sudo[3207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 065piano21 sudo[3210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 13:20:32 065piano21 sudo[3210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 065piano21 sudo[3207]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 065piano21 volumio[1100]: info: Permissions for /etc/hosts set May 20 13:20:32 065piano21 sudo[3199]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 065piano21 volumio[1100]: info: Hostname now is 067piano21 May 20 13:20:32 065piano21 sudo[3216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname 067piano21 May 20 13:20:32 065piano21 sudo[3216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 067piano21 sudo[3216]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 067piano21 volumio[1100]: info: New hostname set May 20 13:20:32 067piano21 sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ May 20 13:20:32 067piano21 sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:32 067piano21 sudo[3218]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 067piano21 volumio[1100]: info: Permissions for /etc/avahi/services/volumio.service May 20 13:20:32 067piano21 avahi-daemon[1094]: Files changed, reloading. May 20 13:20:32 067piano21 avahi-daemon[1094]: Service group file /services/volumio.service changed, reloading. May 20 13:20:32 067piano21 volumio[1100]: info: Avahi name changed to 067piano21 May 20 13:20:32 067piano21 systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... May 20 13:20:32 067piano21 systemd[1]: fix-alpha-hosts.service: Deactivated successfully. May 20 13:20:32 067piano21 systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. May 20 13:20:32 067piano21 sudo[3204]: pam_unix(sudo:session): session closed for user root May 20 13:20:32 067piano21 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 20 13:20:32 067piano21 systemd[1]: shairport-sync.service: Deactivated successfully. May 20 13:20:32 067piano21 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 20 13:20:32 067piano21 systemd[1]: shairport-sync.service: Consumed 3.162s CPU time. May 20 13:20:33 067piano21 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 20 13:20:33 067piano21 sudo[3210]: pam_unix(sudo:session): session closed for user root May 20 13:20:33 067piano21 volumio[1100]: info: Shairport-Sync Started May 20 13:20:33 067piano21 volumio[1100]: info: Successfully Updated MyVolumio device May 20 13:20:33 067piano21 volumio[1100]: info: Discovery: A device disappeared from network May 20 13:20:33 067piano21 volumio[1100]: info: Discovery: Device 065piano21 disappeared from network May 20 13:20:33 067piano21 volumio[1100]: info: Discovery: A device disappeared from network May 20 13:20:33 067piano21 avahi-daemon[1094]: Service "067PIANO21" (/services/volumio.service) successfully established. May 20 13:20:34 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 20 13:20:34 067piano21 volumio[1100]: info: Received Get System Info May 20 13:20:34 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 13:20:34 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 13:20:34 067piano21 volumio[1100]: info: Discovery: Getting this device information May 20 13:20:34 067piano21 volumio[1100]: info: CoreCommandRouter::volumioGetState May 20 13:20:34 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:34 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 13:20:35 067piano21 volumio[1100]: info: TidalConnect service stoped! May 20 13:20:35 067piano21 volumio[1100]: info: TidalConnect service stoped! May 20 13:20:36 067piano21 volumio[1100]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 20 13:20:36 067piano21 volumio[1100]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 20 13:20:36 067piano21 sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 20 13:20:36 067piano21 sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 20 13:20:36 067piano21 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 20 13:20:36 067piano21 sudo[3269]: pam_unix(sudo:session): session closed for user root May 20 13:20:36 067piano21 volumio[1100]: info: Executing endpoint tc_getconfig May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 20 13:20:36 067piano21 vtcs[3274]: STARTING TidalConnect services, version: 1.5.0.45 May 20 13:20:36 067piano21 vtcs[3274]: STARTED TidalConnect services. May 20 13:20:36 067piano21 volumio[1100]: info: Executing endpoint tc_connect May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 20 13:20:36 067piano21 volumio[1100]: info: Connecting to TidalConnect May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::servicePushState May 20 13:20:36 067piano21 volumio[1100]: info: CoreStateMachine::pushState May 20 13:20:36 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::volumioPushState May 20 13:20:36 067piano21 volumio[1100]: info: MRS: Pushing multiroomSync output update for this device May 20 13:20:36 067piano21 volumio[1100]: info: MRS: Pushing multiroomSync output May 20 13:20:36 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:36 067piano21 volumio[1100]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::servicePushState May 20 13:20:36 067piano21 volumio[1100]: info: CoreStateMachine::pushState May 20 13:20:36 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:36 067piano21 volumio[1100]: info: CoreCommandRouter::volumioPushState May 20 13:20:36 067piano21 volumio[1100]: info: MRS: Pushing multiroomSync output update for this device May 20 13:20:36 067piano21 volumio[1100]: info: MRS: Pushing multiroomSync output May 20 13:20:36 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:36 067piano21 volumio[1100]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 20 13:20:36 067piano21 volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 20 13:20:36 067piano21 volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 20 13:20:37 067piano21 volumio[1100]: info: CoreCommandRouter::volumioGetState May 20 13:20:37 067piano21 volumio[1100]: info: CorePlayQueue::getTrack 0 May 20 13:20:37 067piano21 volumio[1100]: info: Discovery: Started advertising with name: 067PIANO21 May 20 13:20:38 067piano21 volumio[1100]: compat.c: read() failed: Resource temporarily unavailable May 20 13:20:38 067piano21 volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 13:20:38 067piano21 volumio[1100]: Error: dns service error: unknown May 20 13:20:38 067piano21 volumio[1100]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 20 13:20:38 067piano21 volumio[1100]: errorCode: -65537 May 20 13:20:38 067piano21 volumio[1100]: } May 20 13:20:38 067piano21 volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 13:20:39 067piano21 sudo[3311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-20 13:19' May 20 13:20:39 067piano21 sudo[3311]: 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="f224c96f24bffeaafa716653a140d091b9b76522" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon May 19 11:28:05 UTC 2025" VOLUMIO_VERSION="0.067" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="178d9db9b0f50342f3fdf788565b826d"